[Freeswitch-users] Outbound calls being dropped after update to Version 1.6.17 -34-0fc0946 64bit
Brian West
brian at freeswitch.org
Wed May 3 19:05:00 MSD 2017
This is a NAT issue, 'sofia global siptrace on' and watch the signaling.
/b
2017-05-03 9:54 GMT-05:00 Charles Bujold <cjbujold at accra.ca>:
> <freeswitch-users at lists.freeswitch.org> Still no solutions, Here is a log
> of a call. Any suggestion would be appreciated
>
>
>
>
>
>
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_core_state_machine.c:643 (sofia/internal/15068559226) State ROUTING
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> mod_sofia.c:143 sofia/internal/15068559226 SOFIA ROUTING
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_ivr_originate.c:67 (sofia/internal/15068559226) State Change
> CS_ROUTING -> CS_CONSUME_MEDIA
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_core_state_machine.c:643 (sofia/internal/15068559226) State
> ROUTING going to sleep
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/15068559226) Running
> State Change CS_CONSUME_MEDIA (Cur 2 Tot 136)
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_core_state_machine.c:662 (sofia/internal/15068559226) State
> CONSUME_MEDIA
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
> switch_core_state_machine.c:662 (sofia/internal/15068559226) State
> CONSUME_MEDIA going to sleep
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.236974 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state
> [proceeding][183]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> sofia.c:7058 Remote SDP:
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828
> IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[
> PCMU:0:8000:20:64000:1]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4491 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++
> is saved as a match
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4436 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[
> G722:9:8000:20:64000:1]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4352 Set telephone-event payload to 101 at 8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:3056 Set Codec sofia/internal/15068559226 PCMU/8000 20
> ms 160 samples 64000 bits 1 channels
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_codec.c:111 sofia/internal/15068559226 Original read codec set
> to PCMU:0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4695 Set telephone-event payload to 101 at 8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:4754 sofia/internal/15068559226 Set 2833 dtmf send
> payload to 101 recv payload to 101
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:6865 AUDIO RTP [sofia/internal/15068559226]
> 192.168.20.161 port 29764 -> 72.55.158.152 port 22274 codec: 0 ms: 20
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:7166 sofia/internal/15068559226 Set 2833 dtmf send
> payload to 101
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:7173 sofia/internal/15068559226 Set 2833 dtmf receive
> payload to 101
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:7196 sofia/internal/15068559226 Set rtp dtmf delay to 40
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_core_media.c:7202 Set comfort noise payload to 13
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [NOTICE]
> sofia_media.c:92 Pre-Answer sofia/internal/15068559226!
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
> switch_channel.c:3473 (sofia/internal/15068559226) Callstate Change DOWN ->
> EARLY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_ivr_originate.c:410 Setting codec string on sofia/internal/
> 200 at 192.168.20.161 to PCMU at 8000h@20i
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [INFO]
> switch_ivr_originate.c:3639 Sending early media
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_media.c:6865 AUDIO RTP [sofia/internal/200 at 192.168.20.161]
> 192.168.20.161 port 16564 -> 192.168.20.150 port 19542 codec: 0 ms: 20
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_media.c:7166 sofia/internal/200 at 192.168.20.161 Set 2833 dtmf
> send payload to 101
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_media.c:7173 sofia/internal/200 at 192.168.20.161 Set 2833 dtmf
> receive payload to 101
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_media.c:7196 sofia/internal/200 at 192.168.20.161 Set rtp dtmf
> delay to 40
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> mod_sofia.c:2363 Ring SDP:
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805535
> IN IP4 192.168.20.161
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [NOTICE]
> mod_sofia.c:2366 Pre-Answer sofia/internal/200 at 192.168.20.161!
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_channel.c:3473 (sofia/internal/200 at 192.168.20.161) Callstate
> Change RINGING -> EARLY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> switch_ivr_originate.c:3690 Originate Resulted in Success:
> [sofia/internal/15068559226]
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
> [early][183]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG]
> switch_ivr_bridge.c:1601 (sofia/internal/15068559226) State Change
> CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/15068559226) Running
> State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 136)
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG]
> switch_core_state_machine.c:653 (sofia/internal/15068559226) State
> EXCHANGE_MEDIA
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG]
> mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.796985 [DEBUG]
> switch_rtp.c:7229 Correct audio ip/port confirmed.
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.877072 [DEBUG]
> switch_rtp.c:7229 Correct audio ip/port confirmed.
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.317073 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state
> [completing][200]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.317073 [DEBUG]
> sofia.c:7055 Duplicate SDP
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828
> IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [ready][200]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [NOTICE]
> sofia.c:8123 Channel [sofia/internal/15068559226] has been answered
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [DEBUG]
> switch_channel.c:3772 (sofia/internal/15068559226) Callstate Change EARLY
> -> ACTIVE
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
> switch_core_media.c:6848 Audio params are unchanged for sofia/internal/
> 200 at 192.168.20.161.
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
> mod_sofia.c:850 Local SDP sofia/internal/200 at 192.168.20.161:
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805536
> IN IP4 192.168.20.161
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [NOTICE]
> switch_ivr_bridge.c:623 Channel [sofia/internal/200 at 192.168.20.161] has
> been answered
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
> switch_channel.c:3772 (sofia/internal/200 at 192.168.20.161) Callstate
> Change EARLY -> ACTIVE
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
> [completed][200]
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.377070 [DEBUG]
> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
> [ready][200]
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.396999 [DEBUG]
> switch_rtp.c:7229 Correct audio ip/port confirmed.
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.396999 [DEBUG]
> switch_rtp.c:7229 Correct audio ip/port confirmed.
>
> 2017-05-03 11:35:00.396999 [INFO] sofia.c:1279 sofia/internal/15068559226
> <(506)%20855-9226> Update Callee ID to "Outbound Call" <+15068559226
> <(506)%20855-9226>>
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:02.717070 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.757073 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state
> [completing][200]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.757073 [DEBUG]
> sofia.c:7055 Duplicate SDP
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 v=0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 o=FreeSWITCH 1493799827 1493799828
> IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 s=FreeSWITCH
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 c=IN IP4 72.55.158.152
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 t=0 0
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 m=audio 22274 RTP/AVP 0 101 13
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:0 PCMU/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:101 telephone-event/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=fmtp:101 0-16
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=rtpmap:13 CN/8000
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 a=ptime:20
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.777033 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [ready][200]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:03.737072 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state [calling][0]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> sofia.c:7048 Channel sofia/internal/15068559226 entering state
> [terminating][408]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [NOTICE]
> sofia.c:8237 Hangup sofia/internal/15068559226 [CS_EXCHANGE_MEDIA]
> [RECOVERY_ON_TIMER_EXPIRE]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_ivr_bridge.c:712 sofia/internal/15068559226 ending bridge by request
> from read function
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/15068559226]*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:653 (sofia/internal/15068559226) State
> EXCHANGE_MEDIA going to sleep*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/15068559226) Running State
> Change CS_HANGUP (Cur 2 Tot 136)*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:850 (sofia/internal/15068559226) Callstate
> Change ACTIVE -> HANGUP*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:852 (sofia/internal/15068559226) State HANGUP*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> mod_sofia.c:438 Channel sofia/internal/15068559226 hanging up, cause:
> RECOVERY_ON_TIMER_EXPIRE*
>
> *3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:60 sofia/internal/15068559226 Standard HANGUP,
> cause: RECOVERY_ON_TIMER_EXPIRE*
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:852 (sofia/internal/15068559226) State HANGUP
> going to sleep
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:619 (sofia/internal/15068559226) State Change
> CS_HANGUP -> CS_REPORTING
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/15068559226) Running
> State Change CS_REPORTING (Cur 2 Tot 136)
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:938 (sofia/internal/15068559226) State
> REPORTING
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:174 sofia/internal/15068559226 Standard
> REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:938 (sofia/internal/15068559226) State
> REPORTING going to sleep
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_state_machine.c:610 (sofia/internal/15068559226) State Change
> CS_REPORTING -> CS_DESTROY
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG]
> switch_core_session.c:1664 Session 136 (sofia/internal/15068559226) Locked,
> Waiting on external entities
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_ivr_bridge.c:706 sofia/internal/15068559226 ending bridge by request
> from write function
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/200 at 192.168.
> 20.161]
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [NOTICE]
> switch_ivr_bridge.c:1751 Hangup sofia/internal/200 at 192.168.20.161
> [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE]
> switch_core_session.c:1682 Session 136 (sofia/internal/15068559226) Ended
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE]
> switch_core_session.c:1686 Close Channel sofia/internal/15068559226
> [CS_DESTROY]
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:741 (sofia/internal/15068559226) Running
> State Change CS_DESTROY (Cur 1 Tot 136)
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_session.c:2814 sofia/internal/200 at 192.168.20.161 skip receive
> message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:650 (sofia/internal/200 at 192.168.20.161) State
> EXECUTE going to sleep
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/200 at 192.168.20.161)
> Running State Change CS_HANGUP (Cur 1 Tot 136)
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:751 (sofia/internal/15068559226) State DESTROY
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
> mod_sofia.c:343 sofia/internal/15068559226 SOFIA DESTROY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:850 (sofia/internal/200 at 192.168.20.161)
> Callstate Change ACTIVE -> HANGUP
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:852 (sofia/internal/200 at 192.168.20.161) State
> HANGUP
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> mod_sofia.c:432 sofia/internal/200 at 192.168.20.161 Overriding SIP cause
> 504 with 408 from the other leg
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> mod_sofia.c:438 Channel sofia/internal/200 at 192.168.20.161 hanging up,
> cause: RECOVERY_ON_TIMER_EXPIRE
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> mod_sofia.c:491 Sending BYE to sofia/internal/200 at 192.168.20.161
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:60 sofia/internal/200 at 192.168.20.161 Standard
> HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:852 (sofia/internal/200 at 192.168.20.161) State
> HANGUP going to sleep
>
> 2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port
> 29764 protocol UDP to localport 29764
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:619 (sofia/internal/200 at 192.168.20.161) State
> Change CS_HANGUP -> CS_REPORTING
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:584 (sofia/internal/200 at 192.168.20.161)
> Running State Change CS_REPORTING (Cur 1 Tot 136)
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:938 (sofia/internal/200 at 192.168.20.161) State
> REPORTING
>
> 2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port
> 29765 protocol UDP to localport 29765
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:181 sofia/internal/15068559226 Standard
> DESTROY
>
> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
> switch_core_state_machine.c:751 (sofia/internal/15068559226) State
> DESTROY going to sleep
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
> switch_core_state_machine.c:174 sofia/internal/200 at 192.168.20.161
> Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
> switch_core_state_machine.c:938 (sofia/internal/200 at 192.168.20.161) State
> REPORTING going to sleep
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
> switch_core_state_machine.c:610 (sofia/internal/200 at 192.168.20.161) State
> Change CS_REPORTING -> CS_DESTROY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
> switch_core_session.c:1664 Session 135 (sofia/internal/200 at 192.168.20.161)
> Locked, Waiting on external entities
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE]
> switch_core_session.c:1682 Session 135 (sofia/internal/200 at 192.168.20.161)
> Ended
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE]
> switch_core_session.c:1686 Close Channel sofia/internal/200 at 192.168.20.161
> [CS_DESTROY]
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
> switch_core_state_machine.c:741 (sofia/internal/200 at 192.168.20.161)
> Running State Change CS_DESTROY (Cur 0 Tot 136)
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
> switch_core_state_machine.c:751 (sofia/internal/200 at 192.168.20.161) State
> DESTROY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
> mod_sofia.c:343 sofia/internal/200 at 192.168.20.161 SOFIA DESTROY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
> switch_core_state_machine.c:181 sofia/internal/200 at 192.168.20.161
> Standard DESTROY
>
> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
> switch_core_state_machine.c:751 (sofia/internal/200 at 192.168.20.161) State
> DESTROY going to sleep
>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
--
*Brian West*
brian at freeswitch.org
*Twitter: @FreeSWITCH , @briankwest*
http://www.freeswitchbook.com
http://www.freeswitchcookbook.com
Book a phone call (CST) <https://freeswitch.com/appointment>
Allison prompts for FreeSWITCH:
*https://www.gofundme.com/allison-prompts-for-freeswitch*
<https://www.gofundme.com/allison-prompts-for-freeswitch>
Got Bugs? Report them here <https://freeswitch.org/jira>! | Reddit:
/r/freeswitch <https://www.reddit.com/r/freeswitch>
*T:*+19184209001 | *F:*+19184209002 | *M:*+1918424WEST (9378)
*Skype:*briankwest
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20170503/338a398b/attachment-0001.html
Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users
mailing list