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