Outbound calls being dropped after update to Version 1.6.17 -34-0fc0946 64bit
<mailto:freeswitch-users@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@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@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@192.168.20.161 to PCMU@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@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@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@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@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@192.168.20.161! cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_channel.c:3473 (sofia/internal/200@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@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@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@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@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@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@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@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 Update Callee ID to "Outbound Call" <+15068559226> 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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@192.168.20.161) State DESTROY going to sleep
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@accra.ca>:
<freeswitch-users@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@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@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@192.168.20.161 to PCMU@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@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@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@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@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@192.168.20.161!
cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_channel.c:3473 (sofia/internal/200@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@192.168.20.161) State DESTROY going to sleep
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@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@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@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
Also, please do not paste logs into the mailing list or report bugs into the mailing list. BUGS belong on jira. On Wed, May 3, 2017 at 10:05 AM, Brian West <brian@freeswitch.org> wrote:
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@accra.ca>:
<freeswitch-users@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 <(506)%20855-9226> 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 <(506)%20855-9226>) State ROUTING
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] mod_sofia.c:143 sofia/internal/15068559226 <(506)%20855-9226> SOFIA ROUTING
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/15068559226 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226> entering state [calling][0]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 <(506)%20855-9226> 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@8000
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:3056 Set Codec sofia/internal/15068559226 <(506)%20855-9226> 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 <(506)%20855-9226> 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@8000
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_core_media.c:4754 sofia/internal/15068559226 <(506)%20855-9226> 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 <(506)%20855-9226> 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 <(506)%20855-9226> 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 <(506)%20855-9226> 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 <(506)%20855-9226> !
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG] switch_channel.c:3473 (sofia/internal/15068559226 <(506)%20855-9226>) 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@192.168.20.161 to PCMU@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@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@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@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@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@192.168.20.161!
cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] switch_channel.c:3473 (sofia/internal/200@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 <(506)%20855-9226>]
cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG] sofia.c:7048 Channel sofia/internal/200@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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226> 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 <(506)%20855-9226> entering state [ready][200]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [NOTICE] sofia.c:8123 Channel [sofia/internal/15068559226 <(506)%20855-9226>] has been answered
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.337073 [DEBUG] switch_channel.c:3772 (sofia/internal/15068559226 <(506)%20855-9226>) 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@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@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@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@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@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@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 <(506)%20855-9226> entering state [calling][0]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:03.757073 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 <(506)%20855-9226> 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 <(506)%20855-9226> entering state [ready][200]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:03.737072 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 <(506)%20855-9226> entering state [calling][0]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] sofia.c:7048 Channel sofia/internal/15068559226 <(506)%20855-9226> entering state [terminating][408]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [NOTICE] sofia.c:8237 Hangup sofia/internal/15068559226 <(506)%20855-9226> [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 <(506)%20855-9226> 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 <(506)%20855-9226>]*
*3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/15068559226 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) State HANGUP*
*3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] mod_sofia.c:438 Channel sofia/internal/15068559226 <(506)%20855-9226> 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 <(506)%20855-9226> 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) State REPORTING
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.737038 [DEBUG] switch_core_state_machine.c:174 sofia/internal/15068559226 <(506)%20855-9226> 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 <(506)%20855-9226>) 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 <(506)%20855-9226>) 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 <(506)%20855-9226> 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@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@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 <(506)%20855-9226> [CS_DESTROY]
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/15068559226 <(506)%20855-9226>) 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@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@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@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 <(506)%20855-9226>) State DESTROY
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] mod_sofia.c:343 sofia/internal/15068559226 <(506)%20855-9226> SOFIA DESTROY
cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/200@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@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@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@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@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@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@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@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@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@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 <(506)%20855-9226> Standard DESTROY
3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/15068559226 <(506)%20855-9226>) 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@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@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@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@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@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@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@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@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@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@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@192.168.20.161) State DESTROY going to sleep
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@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@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@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 <(918)%20420-9001> | *F:*+19184209002 <(918)%20420-9002> | *M:*+1918424WEST (9378) *Skype:*briankwest
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@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@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Anthony Minessale II ♬ @anthmfs ♬ @FreeSWITCH ♬ ☞ http://freeswitch.org/ ☞ http://cluecon.com/ ☞ http://twitter.com/FreeSWITCH ☞ irc.freenode.net #freeswitch ☞ *http://freeswitch.org/g+ <http://freeswitch.org/g+>* ClueCon Weekly Development Call ☎ sip:888@conference.freeswitch.org ☎ +19193869900 https://www.youtube.com/watch?v=9XXgW34t40s https://www.youtube.com/watch?v=NLaDpGQuZDA
participants (3)
-
Anthony Minessale
-
Brian West
-
Charles Bujold