FreeSwitch VAD disconnect по молчанию в канале в течение мин
Добавлено: 16 июн 2016, 02:31
Доброго дня всем!
Есть такая проблема с одним из шлюзов (провайдер Zadarma): если в канале идет тишина (сценарий: позвонил клиент, менеджер ответил, ему клиент задал вопрос, менеджер отошел уточнить и положил трубку на стол) - после минуты тишины рвется связь.
Думал, что это работа VAD с какой - то стороны (на другом gateway такой ситуации нет).
Пробовал отключить VAD, поставив в профиль <param name="suppress-cng" value="true"/> - не помогло, пробовал в диалплане поставить
<action application="set" data="rtcp_audio_interval_msec=5000"/>
<action application="set" data="bridge_generate_comfort_noise=true"/>
пробовал <param name="rtp-timeout-sec" value="0"/>, пробовал также в профайле external менять параметр на больший:
<param name="rtp-timeout-sec" value="600"/>
выставил также параметр
<param name="rtcp-audio-interval-msec" value="5000"/> при бриджинге.
ничего не помогает.
Техподдержка провайдера заявляет, что на их стороне VAD не стоит, никаких SIP параметров они выставить не могут. В логах сказывается такое впечатление, что связь рвется со стороны провайдера по неактивности голосового потока (возможно, ошиабаюсь). Вот логи:
Что можете посоветовать?
Есть такая проблема с одним из шлюзов (провайдер Zadarma): если в канале идет тишина (сценарий: позвонил клиент, менеджер ответил, ему клиент задал вопрос, менеджер отошел уточнить и положил трубку на стол) - после минуты тишины рвется связь.
Думал, что это работа VAD с какой - то стороны (на другом gateway такой ситуации нет).
Пробовал отключить VAD, поставив в профиль <param name="suppress-cng" value="true"/> - не помогло, пробовал в диалплане поставить
<action application="set" data="rtcp_audio_interval_msec=5000"/>
<action application="set" data="bridge_generate_comfort_noise=true"/>
пробовал <param name="rtp-timeout-sec" value="0"/>, пробовал также в профайле external менять параметр на больший:
<param name="rtp-timeout-sec" value="600"/>
выставил также параметр
<param name="rtcp-audio-interval-msec" value="5000"/> при бриджинге.
ничего не помогает.
Техподдержка провайдера заявляет, что на их стороне VAD не стоит, никаких SIP параметров они выставить не могут. В логах сказывается такое впечатление, что связь рвется со стороны провайдера по неактивности голосового потока (возможно, ошиабаюсь). Вот логи:
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
recv 547 bytes from udp/[185.45.152.161]:5060 at 22:43:31.795260:
------------------------------------------------------------------------
BYE sip:471150@85.21.68.163:5080;transport=udp SIP/2.0
Via: SIP/2.0/UDP 185.45.152.161;branch=z9hG4bKaa.22e49ce81f1ad00e56eb6fc0db5cff03.0
Via: SIP/2.0/UDP 185.45.152.132:5060;rport=5060;branch=z9hG4bK51851dd7
Max-Forwards: 69
From: "guest00500" <sip:guest00500@185.45.152.132>;tag=as2e47fc39
To: <sip:471150@185.45.152.161>;tag=7Q44p6a6t9t0D
Call-ID: 1813310b28d64e540bc70be851724d7b@185.45.152.132:5060
CSeq: 103 BYE
User-Agent: Zadarma Voip
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
------------------------------------------------------------------------
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:705 sofia/external/guest00500@185.45.152.132 ending bridge by request from read function
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/external/guest00500@185.45.152.132]
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [NOTICE] sofia.c:952 Hangup sofia/external/guest00500@185.45.152.132 [CS_EXECUTE] [NORMAL_CLEARING]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:699 sofia/external/guest00500@185.45.152.132 ending bridge by request from write function
send 560 bytes to udp/[185.45.152.161]:5060 at 22:43:31.807261:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 185.45.152.161;branch=z9hG4bKaa.22e49ce81f1ad00e56eb6fc0db5cff03.0
Via: SIP/2.0/UDP 185.45.152.132:5060;rport=5060;branch=z9hG4bK51851dd7
From: "guest00500" <sip:guest00500@185.45.152.132>;tag=as2e47fc39
To: <sip:471150@185.45.152.161>;tag=7Q44p6a6t9t0D
Call-ID: 1813310b28d64e540bc70be851724d7b@185.45.152.132:5060
CSeq: 103 BYE
User-Agent: FreeSWITCH-mod_sofia/1.7.0~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1115@192.168.1.34]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_ivr_bridge.c:881 Hangup sofia/internal/1115@192.168.1.34 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1115@192.168.1.34) State EXCHANGE_MEDIA going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1115@192.168.1.34) Running State Change CS_HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1115@192.168.1.34) Callstate Change ACTIVE -> HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1115@192.168.1.34) State HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:425 sofia/internal/1115@192.168.1.34 Overriding SIP cause 480 with 200 from the other leg
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1115@192.168.1.34 hanging up, cause: NORMAL_CLEARING
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:1689 sofia/internal/1115@192.168.1.34 skip receive message [UNBRIDGE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:1692 sofia/external/guest00500@185.45.152.132 skip receive message [UNBRIDGE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_session.c:2796 sofia/external/guest00500@185.45.152.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:539 (sofia/external/guest00500@185.45.152.132) State EXECUTE going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/external/guest00500@185.45.152.132) Running State Change CS_HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_async.c:1305 Stop recording file e:/FreeSWITCH/recordings/zadarma_in/2016-06-15-01-42-30_471150_guest00500.mp3
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/1115@192.168.1.34
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1115@192.168.1.34 Standard HANGUP, cause: NORMAL_CLEARING
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1115@192.168.1.34) State HANGUP going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1115@192.168.1.34) State Change CS_HANGUP -> CS_REPORTING
send 633 bytes to udp/[192.168.1.34]:5060 at 22:43:31.809261:
------------------------------------------------------------------------
BYE sip:1115@192.168.1.34 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.99;rport;branch=z9hG4bK9ev613jNX0X3B
Max-Forwards: 70
From: "DVOROZ_RU_ZASARMA_SITE_BUTTON" <sip:888888@192.168.1.99>;tag=4c0UU2KKXNjjK
To: <sip:1115@192.168.1.34>;tag=v9ZqUd5
Call-ID: 1e4be226-ad24-1234-f099-312aa02db97f
CSeq: 92636012 BYE
User-Agent: FreeSWITCH-mod_sofia/1.7.0~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1115@192.168.1.34) Running State Change CS_REPORTING
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
X-Asterisk-HangupCause: Normal Clearing47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1115@192.168.1.34) State REPORTING
X-Asterisk-HangupCauseCode: 16
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1115@192.168.1.34 Standard REPORTING, cause: NORMAL_CLEARING
------------------------------------------------------------------------
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1115@192.168.1.34) State REPORTING going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1115@192.168.1.34) State Change CS_REPORTING -> CS_DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_session.c:1646 Session 23 (sofia/internal/1115@192.168.1.34) Locked, Waiting on external entities
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_core_session.c:1664 Session 23 (sofia/internal/1115@192.168.1.34) Ended
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1115@192.168.1.34 [CS_DESTROY]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1115@192.168.1.34) Running State Change CS_DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1115@192.168.1.34) State DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:341 sofia/internal/1115@192.168.1.34 SOFIA DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1115@192.168.1.34 Standard DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1115@192.168.1.34) State DESTROY going to sleep
recv 341 bytes from udp/[192.168.1.34]:5060 at 22:43:31.840263:
------------------------------------------------------------------------
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.1.99;rport;branch=z9hG4bK9ev613jNX0X3B
From: "DVOROZ_RU_ZASARMA_SITE_BUTTON" <sip:888888@192.168.1.99>;tag=4c0UU2KKXNjjK
To: <sip:1115@192.168.1.34>;tag=v9ZqUd5
Call-ID: 1e4be226-ad24-1234-f099-312aa02db97f
CSeq: 92636012 BYE
User-Agent: Linphone/3.9.1 (belle-sip/1.4.2)
Supported: outbound
------------------------------------------------------------------------
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_ivr_async.c:1366 Channel is hung up
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_media_bug.c:1118 Removing BUG from sofia/external/guest00500@185.45.152.132
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:739 (sofia/external/guest00500@185.45.152.132) Callstate Change ACTIVE -> HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:741 (sofia/external/guest00500@185.45.152.132) State HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] mod_sofia.c:431 Channel sofia/external/guest00500@185.45.152.132 hanging up, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:60 sofia/external/guest00500@185.45.152.132 Standard HANGUP, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:741 (sofia/external/guest00500@185.45.152.132) State HANGUP going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:508 (sofia/external/guest00500@185.45.152.132) State Change CS_HANGUP -> CS_REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:473 (sofia/external/guest00500@185.45.152.132) Running State Change CS_REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:827 (sofia/external/guest00500@185.45.152.132) State REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:104 sofia/external/guest00500@185.45.152.132 Standard REPORTING, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:827 (sofia/external/guest00500@185.45.152.132) State REPORTING going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:499 (sofia/external/guest00500@185.45.152.132) State Change CS_REPORTING -> CS_DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_session.c:1646 Session 22 (sofia/external/guest00500@185.45.152.132) Locked, Waiting on external entities
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [NOTICE] switch_core_session.c:1664 Session 22 (sofia/external/guest00500@185.45.152.132) Ended
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/guest00500@185.45.152.132 [CS_DESTROY]
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:630 (sofia/external/guest00500@185.45.152.132) Running State Change CS_DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:640 (sofia/external/guest00500@185.45.152.132) State DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] mod_sofia.c:341 sofia/external/guest00500@185.45.152.132 SOFIA DESTROY
2016-06-15 01:43:31.965270 [DEBUG] switch_nat.c:568 unmapped public port 31994 protocol UDP to localport 31994
------------------------------------------------------------------------
BYE sip:471150@85.21.68.163:5080;transport=udp SIP/2.0
Via: SIP/2.0/UDP 185.45.152.161;branch=z9hG4bKaa.22e49ce81f1ad00e56eb6fc0db5cff03.0
Via: SIP/2.0/UDP 185.45.152.132:5060;rport=5060;branch=z9hG4bK51851dd7
Max-Forwards: 69
From: "guest00500" <sip:guest00500@185.45.152.132>;tag=as2e47fc39
To: <sip:471150@185.45.152.161>;tag=7Q44p6a6t9t0D
Call-ID: 1813310b28d64e540bc70be851724d7b@185.45.152.132:5060
CSeq: 103 BYE
User-Agent: Zadarma Voip
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
------------------------------------------------------------------------
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:705 sofia/external/guest00500@185.45.152.132 ending bridge by request from read function
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/external/guest00500@185.45.152.132]
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [NOTICE] sofia.c:952 Hangup sofia/external/guest00500@185.45.152.132 [CS_EXECUTE] [NORMAL_CLEARING]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:699 sofia/external/guest00500@185.45.152.132 ending bridge by request from write function
send 560 bytes to udp/[185.45.152.161]:5060 at 22:43:31.807261:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 185.45.152.161;branch=z9hG4bKaa.22e49ce81f1ad00e56eb6fc0db5cff03.0
Via: SIP/2.0/UDP 185.45.152.132:5060;rport=5060;branch=z9hG4bK51851dd7
From: "guest00500" <sip:guest00500@185.45.152.132>;tag=as2e47fc39
To: <sip:471150@185.45.152.161>;tag=7Q44p6a6t9t0D
Call-ID: 1813310b28d64e540bc70be851724d7b@185.45.152.132:5060
CSeq: 103 BYE
User-Agent: FreeSWITCH-mod_sofia/1.7.0~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1115@192.168.1.34]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_ivr_bridge.c:881 Hangup sofia/internal/1115@192.168.1.34 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1115@192.168.1.34) State EXCHANGE_MEDIA going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1115@192.168.1.34) Running State Change CS_HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1115@192.168.1.34) Callstate Change ACTIVE -> HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1115@192.168.1.34) State HANGUP
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:425 sofia/internal/1115@192.168.1.34 Overriding SIP cause 480 with 200 from the other leg
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1115@192.168.1.34 hanging up, cause: NORMAL_CLEARING
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:1689 sofia/internal/1115@192.168.1.34 skip receive message [UNBRIDGE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_bridge.c:1692 sofia/external/guest00500@185.45.152.132 skip receive message [UNBRIDGE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_session.c:2796 sofia/external/guest00500@185.45.152.132 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:539 (sofia/external/guest00500@185.45.152.132) State EXECUTE going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/external/guest00500@185.45.152.132) Running State Change CS_HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.805261 [DEBUG] switch_ivr_async.c:1305 Stop recording file e:/FreeSWITCH/recordings/zadarma_in/2016-06-15-01-42-30_471150_guest00500.mp3
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/internal/1115@192.168.1.34
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1115@192.168.1.34 Standard HANGUP, cause: NORMAL_CLEARING
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1115@192.168.1.34) State HANGUP going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1115@192.168.1.34) State Change CS_HANGUP -> CS_REPORTING
send 633 bytes to udp/[192.168.1.34]:5060 at 22:43:31.809261:
------------------------------------------------------------------------
BYE sip:1115@192.168.1.34 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.99;rport;branch=z9hG4bK9ev613jNX0X3B
Max-Forwards: 70
From: "DVOROZ_RU_ZASARMA_SITE_BUTTON" <sip:888888@192.168.1.99>;tag=4c0UU2KKXNjjK
To: <sip:1115@192.168.1.34>;tag=v9ZqUd5
Call-ID: 1e4be226-ad24-1234-f099-312aa02db97f
CSeq: 92636012 BYE
User-Agent: FreeSWITCH-mod_sofia/1.7.0~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: path, replaces
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1115@192.168.1.34) Running State Change CS_REPORTING
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
X-Asterisk-HangupCause: Normal Clearing47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1115@192.168.1.34) State REPORTING
X-Asterisk-HangupCauseCode: 16
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1115@192.168.1.34 Standard REPORTING, cause: NORMAL_CLEARING
------------------------------------------------------------------------
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1115@192.168.1.34) State REPORTING going to sleep
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1115@192.168.1.34) State Change CS_REPORTING -> CS_DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_session.c:1646 Session 23 (sofia/internal/1115@192.168.1.34) Locked, Waiting on external entities
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_core_session.c:1664 Session 23 (sofia/internal/1115@192.168.1.34) Ended
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1115@192.168.1.34 [CS_DESTROY]
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1115@192.168.1.34) Running State Change CS_DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1115@192.168.1.34) State DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] mod_sofia.c:341 sofia/internal/1115@192.168.1.34 SOFIA DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1115@192.168.1.34 Standard DESTROY
47e54ff7-cc09-4b12-964f-24423cf80969 2016-06-15 01:43:31.805261 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1115@192.168.1.34) State DESTROY going to sleep
recv 341 bytes from udp/[192.168.1.34]:5060 at 22:43:31.840263:
------------------------------------------------------------------------
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.1.99;rport;branch=z9hG4bK9ev613jNX0X3B
From: "DVOROZ_RU_ZASARMA_SITE_BUTTON" <sip:888888@192.168.1.99>;tag=4c0UU2KKXNjjK
To: <sip:1115@192.168.1.34>;tag=v9ZqUd5
Call-ID: 1e4be226-ad24-1234-f099-312aa02db97f
CSeq: 92636012 BYE
User-Agent: Linphone/3.9.1 (belle-sip/1.4.2)
Supported: outbound
------------------------------------------------------------------------
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_ivr_async.c:1366 Channel is hung up
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_media_bug.c:1118 Removing BUG from sofia/external/guest00500@185.45.152.132
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:739 (sofia/external/guest00500@185.45.152.132) Callstate Change ACTIVE -> HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:741 (sofia/external/guest00500@185.45.152.132) State HANGUP
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] mod_sofia.c:431 Channel sofia/external/guest00500@185.45.152.132 hanging up, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:60 sofia/external/guest00500@185.45.152.132 Standard HANGUP, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:741 (sofia/external/guest00500@185.45.152.132) State HANGUP going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:508 (sofia/external/guest00500@185.45.152.132) State Change CS_HANGUP -> CS_REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:473 (sofia/external/guest00500@185.45.152.132) Running State Change CS_REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:827 (sofia/external/guest00500@185.45.152.132) State REPORTING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:104 sofia/external/guest00500@185.45.152.132 Standard REPORTING, cause: NORMAL_CLEARING
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:827 (sofia/external/guest00500@185.45.152.132) State REPORTING going to sleep
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:499 (sofia/external/guest00500@185.45.152.132) State Change CS_REPORTING -> CS_DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_session.c:1646 Session 22 (sofia/external/guest00500@185.45.152.132) Locked, Waiting on external entities
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [NOTICE] switch_core_session.c:1664 Session 22 (sofia/external/guest00500@185.45.152.132) Ended
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/guest00500@185.45.152.132 [CS_DESTROY]
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:630 (sofia/external/guest00500@185.45.152.132) Running State Change CS_DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] switch_core_state_machine.c:640 (sofia/external/guest00500@185.45.152.132) State DESTROY
ae809723-6071-429e-bf21-3e19c460d968 2016-06-15 01:43:31.855264 [DEBUG] mod_sofia.c:341 sofia/external/guest00500@185.45.152.132 SOFIA DESTROY
2016-06-15 01:43:31.965270 [DEBUG] switch_nat.c:568 unmapped public port 31994 protocol UDP to localport 31994