I have this trouble with fusionpbx. When I make or receive external call from/to gateway all works well. When I make internal call between extension the phone ring, I start talking correctly and all works well for 30 seconds. After this 30 seconds the caller doesn't listen any sound, and the called listen a busy tone as the call has been closed.
Do you have any suggestion? I have fusionpbx installed in a xen hosted vps with debian 8.
Thanks
Carlo
This is the log:
Do you have any suggestion? I have fusionpbx installed in a xen hosted vps with debian 8.
Thanks
Carlo
This is the log:
Code:
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(missed_call_data=)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [missed_call_data]=[UNDEF]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(call_direction=local)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [call_direction]=[local]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 export(origination_callee_id_name=21)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[21]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(RFC2822_DATE=Sat, 02 Sep 2017 13:37:08 +0200)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [RFC2822_DATE]=[Sat, 02 Sep 2017 13:37:08 +0200]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 hash(insert/192.168.20.4-last_dial/31/21)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 eval(not_secure)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 limit(hash 192.168.20.4 21 5 error/user_busy)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] switch_limit.c:126 incr called: 192.168.20.4_21 max:5, interval:0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_hash.c:196 Usage for 192.168.20.4_21 is now 1/5
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(hangup_after_bridge=true)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [hangup_after_bridge]=[true]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(continue_on_fail=true)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.568208 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [continue_on_fail]=[true]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 hash(insert/192.168.20.4-call_return/21/31)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 hash(insert/192.168.20.4-last_dial_ext/21/4e3cc292-897d-4d2a-9b6c-dd1cf0875240)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(called_party_call_group=)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [called_party_call_group]=[UNDEF]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 hash(insert/192.168.20.4-last_dial//4e3cc292-897d-4d2a-9b6c-dd1cf0875240)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 set(api_hangup_hook=lua app.lua hangup)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] mod_dptools.c:1548 SET sofia/internal/31@192.168.20.4 [api_hangup_hook]=[lua app.lua hangup]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 export(domain_name=192.168.20.4)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[192.168.20.4]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 EXECUTE sofia/internal/31@192.168.20.4 bridge(user/21@192.168.20.4)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [domain_name]=[192.168.20.4] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [dialed_extension]=[21] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [origination_callee_id_name]=[21] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [domain_name]=[192.168.20.4] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [domain_name]=[192.168.20.4] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [dialed_extension]=[21] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [origination_callee_id_name]=[21] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_channel.c:1250 sofia/internal/31@192.168.20.4 EXPORTING[export_vars] [domain_name]=[192.168.20.4] to event
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.588206 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/21@192.168.10.81:5060 [ffe97b27-b971-4e3b-a6ec-0e8a91c6836c]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] mod_sofia.c:4819 (sofia/internal/21@192.168.10.81:5060) State Change CS_NEW -> CS_INIT
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_INIT (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/21@192.168.10.81:5060) State INIT
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] mod_sofia.c:90 sofia/internal/21@192.168.10.81:5060 SOFIA INIT
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] sofia_glue.c:1264 sip:21@192.168.10.81:5060;transport=udp Setting proxy route to sofia/internal/21@192.168.10.81:5060
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] sofia_glue.c:1295 sofia/internal/21@192.168.10.81:5060 sending invite version: 1.6.19 -36-7a77e0b 64bit
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c Local SDP:
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c v=0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c o=FreeSWITCH 1504320210 1504320211 IN IP4 192.168.20.4
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c s=FreeSWITCH
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c c=IN IP4 192.168.20.4
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c t=0 0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c m=audio 32018 RTP/AVP 9 0 8 3 101 13
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:9 G722/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:0 PCMU/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:8 PCMA/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:3 GSM/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:101 telephone-event/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=fmtp:101 0-16
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:13 CN/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=ptime:20
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=sendrecv
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:40 sofia/internal/21@192.168.10.81:5060 Standard INIT
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/21@192.168.10.81:5060) State Change CS_INIT -> CS_ROUTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/21@192.168.10.81:5060) State INIT going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_ROUTING (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [calling][0]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/21@192.168.10.81:5060) State ROUTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] mod_sofia.c:143 sofia/internal/21@192.168.10.81:5060 SOFIA ROUTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/21@192.168.10.81:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.588206 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/21@192.168.10.81:5060) State ROUTING going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.608211 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.608211 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/21@192.168.10.81:5060) State CONSUME_MEDIA
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.608211 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/21@192.168.10.81:5060) State CONSUME_MEDIA going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.648210 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [proceeding][180]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.648210 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/21@192.168.10.81:5060!
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:08.648210 [DEBUG] switch_channel.c:3346 (sofia/internal/21@192.168.10.81:5060) Callstate Change DOWN -> RINGING
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [INFO] switch_ivr_originate.c:1215 Sending early media
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/31@192.168.20.4] 192.168.20.4 port 28780 -> 192.168.10.86 port 5004 codec: 9 ms: 20
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_core_media.c:7179 sofia/internal/31@192.168.20.4 Set 2833 dtmf send payload to 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_core_media.c:7186 sofia/internal/31@192.168.20.4 Set 2833 dtmf receive payload to 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_core_media.c:7209 sofia/internal/31@192.168.20.4 Set rtp dtmf delay to 40
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] mod_sofia.c:2364 Ring SDP:
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 v=0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 o=FreeSWITCH 1504323448 1504323449 IN IP4 192.168.20.4
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 s=FreeSWITCH
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 c=IN IP4 192.168.20.4
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 t=0 0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 m=audio 28780 RTP/AVP 9 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:9 G722/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:101 telephone-event/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=fmtp:101 0-16
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=ptime:20
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=sendrecv
4e3cc292-897d-4d2a-9b6c-dd1cf0875240
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/internal/31@192.168.20.4!
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_channel.c:3474 (sofia/internal/31@192.168.20.4) Callstate Change RINGING -> EARLY
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] sofia.c:7084 Channel sofia/internal/31@192.168.20.4 entering state [early][183]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@16000hz 1 channel 20ms
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_core_codec.c:223 sofia/internal/31@192.168.20.4 Push codec L16:100
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.648210 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:08.888209 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [completing][200]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] sofia.c:7094 Remote SDP:
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c v=0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c o=21 8000 8000 IN IP4 192.168.10.81
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c s=SIP Call
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c c=IN IP4 192.168.10.81
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c t=0 0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c m=audio 5020 RTP/AVP 9 13
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:9 G722/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=ptime:20
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [ready][200]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/21@192.168.10.81:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_codec.c:111 sofia/internal/21@192.168.10.81:5060 Original read codec set to G722:9
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:4774 No 2833 in SDP. Disable 2833 dtmf and switch to INFO
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/21@192.168.10.81:5060] 192.168.20.4 port 32018 -> 192.168.10.81 port 5020 codec: 9 ms: 20
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:7209 sofia/internal/21@192.168.10.81:5060 Set rtp dtmf delay to 40
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:7215 Set comfort noise payload to 13
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [NOTICE] sofia.c:8218 Channel [sofia/internal/21@192.168.10.81:5060] has been answered
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_channel.c:3773 (sofia/internal/21@192.168.10.81:5060) Callstate Change RINGING -> ACTIVE
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] switch_core_codec.c:248 sofia/internal/31@192.168.20.4 Restore previous codec G722:9.
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/31@192.168.20.4.
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/31@192.168.20.4:
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 v=0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 o=FreeSWITCH 1504323448 1504323450 IN IP4 192.168.20.4
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 s=FreeSWITCH
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 c=IN IP4 192.168.20.4
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 t=0 0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 m=audio 28780 RTP/AVP 9 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:9 G722/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:101 telephone-event/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=fmtp:101 0-16
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=ptime:20
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=sendrecv
4e3cc292-897d-4d2a-9b6c-dd1cf0875240
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] sofia.c:7084 Channel sofia/internal/31@192.168.20.4 entering state [completed][200]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/31@192.168.20.4] has been answered
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] switch_channel.c:3773 (sofia/internal/31@192.168.20.4) Callstate Change EARLY -> ACTIVE
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/21@192.168.10.81:5060]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.468410 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/21@192.168.10.81:5060]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/21@192.168.10.81:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/21@192.168.10.81:5060) State EXCHANGE_MEDIA
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.468410 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.548209 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.608219 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.768218 [DEBUG] sofia.c:7084 Channel sofia/internal/31@192.168.20.4 entering state [ready][200]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:14.788209 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [calling][0]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:14.788209 [DEBUG] sofia.c:7084 Channel sofia/internal/31@192.168.20.4 entering state [calling][0]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] sofia.c:7084 Channel sofia/internal/21@192.168.10.81:5060 entering state [ready][200]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] sofia.c:7094 Remote SDP:
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c v=0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c o=21 8000 8001 IN IP4 192.168.10.81
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c s=SIP Call
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c c=IN IP4 192.168.10.81
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c t=0 0
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c m=audio 5020 RTP/AVP 9 13
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=rtpmap:9 G722/8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c a=ptime:20
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4373 Set comfort noise payload to 13
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4720 Set comfort noise payload to 13@8000
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:4774 No 2833 in SDP. Disable 2833 dtmf and switch to INFO
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] sofia.c:8061 Processing updated SDP
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:15.008207 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/21@192.168.10.81:5060.
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] sofia.c:7084 Channel sofia/internal/31@192.168.20.4 entering state [ready][200]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] sofia.c:7094 Remote SDP:
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 v=0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 o=31 8000 8001 IN IP4 192.168.10.86
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 s=SIP Call
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 c=IN IP4 192.168.10.86
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 t=0 0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 m=audio 5004 RTP/AVP 9 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:9 G722/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=rtpmap:101 telephone-event/8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=fmtp:101 0-15
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 a=ptime:20
4e3cc292-897d-4d2a-9b6c-dd1cf0875240
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:4767 sofia/internal/31@192.168.20.4 Set 2833 dtmf send payload to 101 recv payload to 101
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] sofia.c:8061 Processing updated SDP
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:15.408400 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/31@192.168.20.4.
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.888207 [NOTICE] sofia.c:1012 Hangup sofia/internal/31@192.168.20.4 [CS_EXECUTE] [NORMAL_CLEARING]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.888207 [DEBUG] mod_hash.c:297 Usage for 192.168.20.4_21 is now 0
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.888207 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/31@192.168.20.4]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/31@192.168.20.4 ending bridge by request from write function
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/21@192.168.10.81:5060]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/21@192.168.10.81:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/21@192.168.10.81:5060) State EXCHANGE_MEDIA going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_HANGUP (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/21@192.168.10.81:5060) Callstate Change ACTIVE -> HANGUP
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/21@192.168.10.81:5060) State HANGUP
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] mod_sofia.c:432 sofia/internal/21@192.168.10.81:5060 Overriding SIP cause 480 with 200 from the other leg
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] mod_sofia.c:438 Channel sofia/internal/21@192.168.10.81:5060 hanging up, cause: NORMAL_CLEARING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/21@192.168.10.81:5060
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:60 sofia/internal/21@192.168.10.81:5060 Standard HANGUP, cause: NORMAL_CLEARING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/21@192.168.10.81:5060) State HANGUP going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/21@192.168.10.81:5060) State Change CS_HANGUP -> CS_REPORTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_REPORTING (Cur 2 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/21@192.168.10.81:5060) State REPORTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:174 sofia/internal/21@192.168.10.81:5060 Standard REPORTING, cause: NORMAL_CLEARING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/21@192.168.10.81:5060) State REPORTING going to sleep
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/21@192.168.10.81:5060) State Change CS_REPORTING -> CS_DESTROY
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_session.c:1665 Session 459 (sofia/internal/21@192.168.10.81:5060) Locked, Waiting on external entities
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/31@192.168.20.4 skip receive message [UNBRIDGE] (channel is hungup already)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_session.c:2815 sofia/internal/31@192.168.20.4 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/31@192.168.20.4) State EXECUTE going to sleep
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/31@192.168.20.4) Running State Change CS_HANGUP (Cur 2 Tot 459)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/31@192.168.20.4) Callstate Change ACTIVE -> HANGUP
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/31@192.168.20.4) State HANGUP
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] mod_sofia.c:438 Channel sofia/internal/31@192.168.20.4 hanging up, cause: NORMAL_CLEARING
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:60 sofia/internal/31@192.168.20.4 Standard HANGUP, cause: NORMAL_CLEARING
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/31@192.168.20.4) State HANGUP going to sleep
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
4e3cc292-897d-4d2a-9b6c-dd1cf0875240
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/31@192.168.20.4) State Change CS_HANGUP -> CS_REPORTING
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/31@192.168.20.4) Running State Change CS_REPORTING (Cur 2 Tot 459)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/31@192.168.20.4) State REPORTING
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [NOTICE] switch_core_session.c:1683 Session 459 (sofia/internal/21@192.168.10.81:5060) Ended
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/21@192.168.10.81:5060 [CS_DESTROY]
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/21@192.168.10.81:5060) Running State Change CS_DESTROY (Cur 1 Tot 459)
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/21@192.168.10.81:5060) State DESTROY
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] mod_sofia.c:343 sofia/internal/21@192.168.10.81:5060 SOFIA DESTROY
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:181 sofia/internal/21@192.168.10.81:5060 Standard DESTROY
ffe97b27-b971-4e3b-a6ec-0e8a91c6836c 2017-09-02 13:37:49.908313 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/21@192.168.10.81:5060) State DESTROY going to sleep
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:174 sofia/internal/31@192.168.20.4 Standard REPORTING, cause: NORMAL_CLEARING
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/31@192.168.20.4) State REPORTING going to sleep
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/31@192.168.20.4) State Change CS_REPORTING -> CS_DESTROY
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_session.c:1665 Session 458 (sofia/internal/31@192.168.20.4) Locked, Waiting on external entities
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [NOTICE] switch_core_session.c:1683 Session 458 (sofia/internal/31@192.168.20.4) Ended
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/31@192.168.20.4 [CS_DESTROY]
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/31@192.168.20.4) Running State Change CS_DESTROY (Cur 0 Tot 459)
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/31@192.168.20.4) State DESTROY
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] mod_sofia.c:343 sofia/internal/31@192.168.20.4 SOFIA DESTROY
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:181 sofia/internal/31@192.168.20.4 Standard DESTROY
4e3cc292-897d-4d2a-9b6c-dd1cf0875240 2017-09-02 13:37:50.128211 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/31@192.168.20.4) State DESTROY going to sleep