2017-02-24 10:47:01.145784 [NOTICE] switch_cpp.cpp:86 bound to all 2017-02-24 10:47:01.145784 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7f67080507a0 Connected. 2017-02-24 10:47:01.145784 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables 2017-02-24 10:47:01.145784 [DEBUG] switch_ivr_originate.c:2664 Parsing session specific variables 2017-02-24 10:47:01.145784 [NOTICE] switch_channel.c:1104 New Channel sofia/external/%2B2348124523790@10.200.161.132 [20864a79-28c5-4c40-a23b-08ef4087c2db] 2017-02-24 10:47:01.145784 [DEBUG] mod_sofia.c:4943 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_NEW -> CS_INIT 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_INIT (Cur 1 Tot 15) 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2348124523790@10.200.161.132) State INIT 2017-02-24 10:47:01.145784 [DEBUG] mod_sofia.c:92 sofia/external/%2B2348124523790@10.200.161.132 SOFIA INIT 2017-02-24 10:47:01.145784 [DEBUG] sofia_glue.c:1284 sofia/external/%2B2348124523790@10.200.161.132 sending invite version: 1.9.0 git 1aadfc0 2017-02-03 00:47:05Z 64bit Local SDP: v=0 o=FreeSWITCH 1487908379 1487908380 IN IP4 10.227.49.19 s=FreeSWITCH c=IN IP4 10.227.49.19 t=0 0 m=audio 21242 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 17228 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:40 sofia/external/%2B2348124523790@10.200.161.132 Standard INIT 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:48 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_INIT -> CS_ROUTING 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2348124523790@10.200.161.132) State INIT going to sleep 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_ROUTING (Cur 1 Tot 15) 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING 2017-02-24 10:47:01.145784 [DEBUG] mod_sofia.c:145 sofia/external/%2B2348124523790@10.200.161.132 SOFIA ROUTING 2017-02-24 10:47:01.145784 [DEBUG] switch_ivr_originate.c:67 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING going to sleep send 1419 bytes to udp/[10.200.161.132]:5060 at 10:47:01.163379: ------------------------------------------------------------------------ INVITE sip:+2348124523790@10.200.161.132 SIP/2.0 Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB Max-Forwards: 70 From: "" ;tag=Sm4Q9cUN6rpXg 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 15) To: Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170203T004705Z~1aadfc0fa4~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Allow-Events: talk, hold, conference, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 621 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA going to sleep X-FS-Support: update_display,send_info P-Asserted-Identity: v=0 o=FreeSWITCH 1487908379 1487908380 IN IP4 10.227.49.19 s=FreeSWITCH c=IN IP4 10.227.49.19 t=0 0 m=audio 21242 RTP/AVP 102 9 0 8 104 101 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:104 telephone-event/48000 a=fmtp:104 0-16 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 m=video 17228 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ 2017-02-24 10:47:01.145784 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [calling][0] recv 311 bytes from udp/[10.200.161.132]:5060 at 10:47:01.184955: ------------------------------------------------------------------------ SIP/2.0 100 Trying From: "" ;tag=Sm4Q9cUN6rpXg To: Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 862 bytes from udp/[10.200.161.132]:5060 at 10:47:01.455010: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress From: "" ;tag=Sm4Q9cUN6rpXg To: ;tag=0259576292 Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 INVITE Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE Content-Type: application/sdp Contact: Content-Length: 394 v=0 o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.31 t=0 0 a=sendrecv m=audio 12674 RTP/AVP 8 101 c=IN IP4 10.200.12.31 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:40 m=video 0 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ 2017-02-24 10:47:01.445797 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][183] 2017-02-24 10:47:01.445797 [DEBUG] sofia.c:7242 Remote SDP: v=0 o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.31 t=0 0 a=sendrecv m=audio 12674 RTP/AVP 8 101 c=IN IP4 10.200.12.31 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:40 m=video 0 RTP/AVP 103 b=AS:1024 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4858 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[opus:116:48000:20:0:1] 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4858 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[G722:9:8000:20:64000:1] 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4858 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMU:0:8000:20:64000:1] 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4858 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMA:8:8000:20:64000:1] 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4913 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4774 Set telephone-event payload to 101@8000 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:3389 Set Codec sofia/external/%2B2348124523790@10.200.161.132 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2017-02-24 10:47:01.445797 [DEBUG] switch_core_codec.c:111 sofia/external/%2B2348124523790@10.200.161.132 Original read codec set to PCMA:8 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:5117 Set telephone-event payload to 101@8000 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:5176 sofia/external/%2B2348124523790@10.200.161.132 Set 2833 dtmf send payload to 101 recv payload to 101 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:7764 AUDIO RTP [sofia/external/%2B2348124523790@10.200.161.132] 10.227.49.19 port 21242 -> 10.200.12.31 port 12674 codec: 8 ms: 20 2017-02-24 10:47:01.445797 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:8072 sofia/external/%2B2348124523790@10.200.161.132 Set 2833 dtmf send payload to 101 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:8079 sofia/external/%2B2348124523790@10.200.161.132 Set 2833 dtmf receive payload to 101 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:8102 sofia/external/%2B2348124523790@10.200.161.132 Set rtp dtmf delay to 40 2017-02-24 10:47:01.445797 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/%2B2348124523790@10.200.161.132! 2017-02-24 10:47:01.445797 [DEBUG] switch_channel.c:3473 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change DOWN -> EARLY 2017-02-24 10:47:01.445797 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/external/%2B2348124523790@10.200.161.132] 2017-02-24 10:47:01.445797 [DEBUG] switch_cpp.cpp:628 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_SOFT_EXECUTE (Cur 1 Tot 15) 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:656 (sofia/external/%2B2348124523790@10.200.161.132) State SOFT_EXECUTE 2017-02-24 10:47:01.445797 [DEBUG] mod_sofia.c:641 SOFIA SOFT_EXECUTE 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:400 sofia/external/%2B2348124523790@10.200.161.132 Standard SOFT_EXECUTE 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:656 (sofia/external/%2B2348124523790@10.200.161.132) State SOFT_EXECUTE going to sleep 2017-02-24 10:47:01.505710 [DEBUG] switch_rtp.c:7021 Correct audio ip/port confirmed. 2017-02-24 10:47:03.445798 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms recv 377 bytes from udp/[10.200.161.132]:5060 at 10:47:06.213928: ------------------------------------------------------------------------ SIP/2.0 180 Ringing From: "" ;tag=Sm4Q9cUN6rpXg To: ;tag=0259576292 Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 INVITE Contact: Content-Length: 0 ------------------------------------------------------------------------ 2017-02-24 10:47:06.205797 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][180] 2017-02-24 10:47:06.205797 [NOTICE] sofia.c:7340 Ring-Ready sofia/external/%2B2348124523790@10.200.161.132! 2017-02-24 10:47:06.205797 [DEBUG] switch_channel.c:3345 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change EARLY -> RINGING 2017-02-24 10:47:12.185797 [DEBUG] switch_rtp.c:7257 RTP RECV DTMF *:560 2017-02-24 10:47:12.185797 [INFO] switch_channel.c:515 RECV DTMF *:560 2017-02-24 10:47:12.185797 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/DI/U_know.wav 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_OUTGOING 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CREATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_ORIGINATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CODEC 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CODEC 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_PROGRESS_MEDIA 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 PLAYBACK_START 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_PROGRESS 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 DTMF 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 PLAYBACK_STOP recv 873 bytes from udp/[10.200.161.132]:5060 at 10:47:17.515126: ------------------------------------------------------------------------ SIP/2.0 200 OK From: "" ;tag=Sm4Q9cUN6rpXg To: ;tag=0259576292 Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 INVITE Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE Accept: application/sdp Content-Type: application/sdp Contact: Content-Length: 394 v=0 o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.31 t=0 0 a=sendrecv m=audio 12674 RTP/AVP 8 101 c=IN IP4 10.200.12.31 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:40 m=video 0 RTP/AVP 103 b=AS:1024 a=rtpmap:103 VP8/90000 a=rtcp-fb:103 ccm fir a=rtcp-fb:103 ccm tmmbr a=rtcp-fb:103 nack a=rtcp-fb:103 nack pli ------------------------------------------------------------------------ 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [completing][200] 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7239 Duplicate SDP v=0 o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.31 t=0 0 a=sendrecv m=audio 12674 RTP/AVP 8 101 c=IN IP4 10.200.12.31 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=maxptime:40 m=video 0 RTP/AVP 103 b=AS:1024 send 390 bytes to udp/[10.200.161.132]:5060 at 10:47:17.516804: ------------------------------------------------------------------------ ACK sip:10.200.161.132:5060;transport=UDP SIP/2.0 Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK50rHNSpBQpKaQ Max-Forwards: 70 From: "" ;tag=Sm4Q9cUN6rpXg To: ;tag=0259576292 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 103628746 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [ready][200] 2017-02-24 10:47:17.505799 [NOTICE] sofia.c:8295 Channel [sofia/external/%2B2348124523790@10.200.161.132] has been answered 2017-02-24 10:47:17.505799 [INFO] switch_cpp.cpp:1377 CHANNEL_ANSWER 2017-02-24 10:47:17.505799 [DEBUG] switch_channel.c:3772 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change RINGING -> ACTIVE 2017-02-24 10:47:17.505799 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:47:46.365769 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:47:46.365769 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:48:06.385797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:48:06.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:48:46.385799 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:48:46.385799 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:49:06.405796 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:49:06.405796 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE recv 363 bytes from udp/[10.200.161.132]:5060 at 10:49:08.738429: ------------------------------------------------------------------------ BYE sip:mod_sofia@10.227.47.13:5080 SIP/2.0 From: ;tag=0259576292 To: "" ;tag=Sm4Q9cUN6rpXg Max-Forwards: 70 Via: SIP/2.0/UDP 10.200.161.132:5060;branch=z9hG4bK00000025204689020616 Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 1 BYE Reason: Q.850;cause=16 Content-Length: 0 ------------------------------------------------------------------------ 2017-02-24 10:49:08.725807 [NOTICE] sofia.c:1079 Hangup sofia/external/%2B2348124523790@10.200.161.132 [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_HANGUP (Cur 1 Tot 15) 2017-02-24 10:49:08.725807 [INFO] switch_cpp.cpp:1377 CHANNEL_HANGUP 2017-02-24 10:49:08.725807 [INFO] switch_cpp.cpp:1377 ObSession:hangupCause() = NORMAL_CLEARING send 491 bytes to udp/[10.200.161.132]:5060 at 10:49:08.740153: ------------------------------------------------------------------------ 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:850 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change ACTIVE -> HANGUP SIP/2.0 200 OK Via: SIP/2.0/UDP 10.200.161.132:5060;branch=z9hG4bK00000025204689020616 From: ;tag=0259576292 To: "" ;tag=Sm4Q9cUN6rpXg Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e CSeq: 1 BYE User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170203T004705Z~1aadfc0fa4~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY Supported: timer, path, replaces Content-Length: 0 ------------------------------------------------------------------------ 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP 2017-02-24 10:49:08.725807 [DEBUG] mod_sofia.c:440 Channel sofia/external/%2B2348124523790@10.200.161.132 hanging up, cause: NORMAL_CLEARING 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:60 sofia/external/%2B2348124523790@10.200.161.132 Standard HANGUP, cause: NORMAL_CLEARING 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP going to sleep 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:619 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_HANGUP -> CS_REPORTING 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_REPORTING (Cur 1 Tot 15) 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:174 sofia/external/%2B2348124523790@10.200.161.132 Standard REPORTING, cause: NORMAL_CLEARING 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING going to sleep 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:610 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_REPORTING -> CS_DESTROY 2017-02-24 10:49:08.725807 [DEBUG] switch_core_session.c:1712 Session 15 (sofia/external/%2B2348124523790@10.200.161.132) Locked, Waiting on external entities 2017-02-24 10:49:08.725807 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7f67080507a0 released. 2017-02-24 10:49:08.725807 [DEBUG] switch_cpp.cpp:1123 sofia/external/%2B2348124523790@10.200.161.132 destroy/unlink session from object 2017-02-24 10:49:08.725807 [NOTICE] switch_event.c:2141 Event Binding deleted for src/switch_cpp.cpp:ALL 2017-02-24 10:49:08.725807 [NOTICE] switch_core_session.c:1730 Session 15 (sofia/external/%2B2348124523790@10.200.161.132) Ended 2017-02-24 10:49:08.725807 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/%2B2348124523790@10.200.161.132 [CS_DESTROY] 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:741 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_DESTROY (Cur 0 Tot 15) 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY 2017-02-24 10:49:08.725807 [DEBUG] mod_sofia.c:345 sofia/external/%2B2348124523790@10.200.161.132 SOFIA DESTROY 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:181 sofia/external/%2B2348124523790@10.200.161.132 Standard DESTROY 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY going to sleep freeswitch@Peth-ivr3>