[Freeswitch-users] Outbound calls being dropped after update to Version 1.6.17 -34-0fc0946 64bit
Anthony Minessale
anthony.minessale at gmail.com
Wed May 3 19:44:24 MSD 2017
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 at 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 at accra.ca>:
>
>> <freeswitch-users at lists.freeswitch.org> Still no solutions, Here is a
>> log of a call. Any suggestion would be appreciated
>>
>>
>>
>>
>>
>>
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:57.156987 [DEBUG]
>> sofia.c:7048 Channel sofia/internal/15068559226 <(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 at 8000
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.737079 [DEBUG]
>> switch_core_media.c:3056 Set Codec sofia/internal/15068559226
>> <(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 at 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 at 192.168.20.161 to PCMU at 8000h@20i
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [INFO]
>> switch_ivr_originate.c:3639 Sending early media
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_core_media.c:6865 AUDIO RTP [sofia/internal/200 at 192.168.20.161]
>> 192.168.20.161 port 16564 -> 192.168.20.150 port 19542 codec: 0 ms: 20
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_core_media.c:7166 sofia/internal/200 at 192.168.20.161 Set 2833 dtmf
>> send payload to 101
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_core_media.c:7173 sofia/internal/200 at 192.168.20.161 Set 2833 dtmf
>> receive payload to 101
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_core_media.c:7196 sofia/internal/200 at 192.168.20.161 Set rtp dtmf
>> delay to 40
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> mod_sofia.c:2363 Ring SDP:
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805535
>> IN IP4 192.168.20.161
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [NOTICE]
>> mod_sofia.c:2366 Pre-Answer sofia/internal/200 at 192.168.20.161!
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_channel.c:3473 (sofia/internal/200 at 192.168.20.161) Callstate
>> Change RINGING -> EARLY
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_ivr_originate.c:3690 Originate Resulted in Success: [sofia/internal/
>> 15068559226 <(506)%20855-9226>]
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:34:58.757038 [DEBUG]
>> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
>> [early][183]
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:34:58.757038 [DEBUG]
>> switch_ivr_bridge.c:1601 (sofia/internal/15068559226 <(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 at 192.168.20.161.
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
>> mod_sofia.c:850 Local SDP sofia/internal/200 at 192.168.20.161:
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c v=0
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c o=FreeSWITCH 1493805534 1493805536
>> IN IP4 192.168.20.161
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c s=FreeSWITCH
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c c=IN IP4 192.168.20.161
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c t=0 0
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c m=audio 16564 RTP/AVP 0 101
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:0 PCMU/8000
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=rtpmap:101 telephone-event/8000
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=fmtp:101 0-16
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=ptime:20
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c a=sendrecv
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [NOTICE]
>> switch_ivr_bridge.c:623 Channel [sofia/internal/200 at 192.168.20.161] has
>> been answered
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
>> switch_channel.c:3772 (sofia/internal/200 at 192.168.20.161) Callstate
>> Change EARLY -> ACTIVE
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.357041 [DEBUG]
>> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
>> [completed][200]
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.377070 [DEBUG]
>> sofia.c:7048 Channel sofia/internal/200 at 192.168.20.161 entering state
>> [ready][200]
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:35:00.396999 [DEBUG]
>> switch_rtp.c:7229 Correct audio ip/port confirmed.
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:35:00.396999 [DEBUG]
>> switch_rtp.c:7229 Correct audio ip/port confirmed.
>>
>> 2017-05-03 11:35:00.396999 [INFO] sofia.c:1279 sofia/internal/15068559226
>> <(506)%20855-9226> Update Callee ID to "Outbound Call" <+15068559226
>> <(506)%20855-9226>>
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:36:02.717070 [DEBUG]
>> sofia.c:7048 Channel sofia/internal/15068559226 <(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 at 192.168.20
>> .161]
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [NOTICE]
>> switch_ivr_bridge.c:1751 Hangup sofia/internal/200 at 192.168.20.161
>> [CS_EXECUTE] [RECOVERY_ON_TIMER_EXPIRE]
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE]
>> switch_core_session.c:1682 Session 136 (sofia/internal/15068559226) Ended
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [NOTICE]
>> switch_core_session.c:1686 Close Channel sofia/internal/15068559226
>> <(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 at 192.168.20.161 skip
>> receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:650 (sofia/internal/200 at 192.168.20.161)
>> State EXECUTE going to sleep
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:584 (sofia/internal/200 at 192.168.20.161)
>> Running State Change CS_HANGUP (Cur 1 Tot 136)
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:751 (sofia/internal/15068559226
>> <(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 at 192.168.20.161)
>> Callstate Change ACTIVE -> HANGUP
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:852 (sofia/internal/200 at 192.168.20.161)
>> State HANGUP
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> mod_sofia.c:432 sofia/internal/200 at 192.168.20.161 Overriding SIP cause
>> 504 with 408 from the other leg
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> mod_sofia.c:438 Channel sofia/internal/200 at 192.168.20.161 hanging up,
>> cause: RECOVERY_ON_TIMER_EXPIRE
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> mod_sofia.c:491 Sending BYE to sofia/internal/200 at 192.168.20.161
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:60 sofia/internal/200 at 192.168.20.161
>> Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:852 (sofia/internal/200 at 192.168.20.161)
>> State HANGUP going to sleep
>>
>> 2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port
>> 29764 protocol UDP to localport 29764
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:619 (sofia/internal/200 at 192.168.20.161)
>> State Change CS_HANGUP -> CS_REPORTING
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:584 (sofia/internal/200 at 192.168.20.161)
>> Running State Change CS_REPORTING (Cur 1 Tot 136)
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:938 (sofia/internal/200 at 192.168.20.161)
>> State REPORTING
>>
>> 2017-05-03 11:37:35.757070 [DEBUG] switch_nat.c:542 unmapped public port
>> 29765 protocol UDP to localport 29765
>>
>> 3960b42a-6c36-49c9-96a0-a844b3f0f259 2017-05-03 11:37:35.757070 [DEBUG]
>> switch_core_state_machine.c:181 sofia/internal/15068559226
>> <(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 at 192.168.20.161
>> Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
>> switch_core_state_machine.c:938 (sofia/internal/200 at 192.168.20.161)
>> State REPORTING going to sleep
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
>> switch_core_state_machine.c:610 (sofia/internal/200 at 192.168.20.161)
>> State Change CS_REPORTING -> CS_DESTROY
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
>> switch_core_session.c:1664 Session 135 (sofia/internal/200 at 192.168.20.161)
>> Locked, Waiting on external entities
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE]
>> switch_core_session.c:1682 Session 135 (sofia/internal/200 at 192.168.20.161)
>> Ended
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [NOTICE]
>> switch_core_session.c:1686 Close Channel sofia/internal/200 at 192.168.20.
>> 161 [CS_DESTROY]
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.776979 [DEBUG]
>> switch_core_state_machine.c:741 (sofia/internal/200 at 192.168.20.161)
>> Running State Change CS_DESTROY (Cur 0 Tot 136)
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
>> switch_core_state_machine.c:751 (sofia/internal/200 at 192.168.20.161)
>> State DESTROY
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
>> mod_sofia.c:343 sofia/internal/200 at 192.168.20.161 SOFIA DESTROY
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
>> switch_core_state_machine.c:181 sofia/internal/200 at 192.168.20.161
>> Standard DESTROY
>>
>> cc1d0c18-e576-47a6-bf4f-35ab835f920c 2017-05-03 11:37:35.796986 [DEBUG]
>> switch_core_state_machine.c:751 (sofia/internal/200 at 192.168.20.161)
>> State DESTROY going to sleep
>>
>>
>>
>> _________________________________________________________________________
>> Professional FreeSWITCH Consulting Services:
>> consulting at freeswitch.org
>> http://www.freeswitchsolutions.com
>>
>> Official FreeSWITCH Sites
>> http://www.freeswitch.org
>> http://confluence.freeswitch.org
>> http://www.cluecon.com
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>
>
>
> --
>
> *Brian West*
> brian at freeswitch.org
>
> *Twitter: @FreeSWITCH , @briankwest*
>
> http://www.freeswitchbook.com
> http://www.freeswitchcookbook.com
>
> Book a phone call (CST) <https://freeswitch.com/appointment>
>
> Allison prompts for FreeSWITCH:
>
> *https://www.gofundme.com/allison-prompts-for-freeswitch*
> <https://www.gofundme.com/allison-prompts-for-freeswitch>
>
> Got Bugs? Report them here <https://freeswitch.org/jira>! | Reddit:
> /r/freeswitch <https://www.reddit.com/r/freeswitch>
>
> *T:*+19184209001 <(918)%20420-9001> | *F:*+19184209002 <(918)%20420-9002>
> | *M:*+1918424WEST (9378)
> *Skype:*briankwest
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
--
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 at conference.freeswitch.org ☎ +19193869900
https://www.youtube.com/watch?v=9XXgW34t40s
https://www.youtube.com/watch?v=NLaDpGQuZDA
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20170503/851c6600/attachment-0001.html
Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users
mailing list