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>:

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

 

_________________________________________________________________________
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)

Allison prompts for FreeSWITCH:

https://www.gofundme.com/allison-prompts-for-freeswitch

Got Bugs? Report them here! | Reddit: /r/freeswitch

T:+19184209001 | F:+19184209002 | 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