<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