freeswitch@Peth-ivr3> freeswitch@Peth-ivr3> luarun Bkp_newObd.lua +OK 2017-02-24 10:30:18.705799 [NOTICE] switch_cpp.cpp:86 bound to all 2017-02-24 10:30:18.705799 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7f67100463c0 Connected. 2017-02-24 10:30:18.705799 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables 2017-02-24 10:30:18.705799 [DEBUG] switch_ivr_originate.c:2664 Parsing session specific variables 2017-02-24 10:30:18.705799 [NOTICE] switch_channel.c:1104 New Channel sofia/external/%2B2348124523790@10.200.161.132 [2b7d2ef2-dd47-4dba-b85a-c02bff3362f3] 2017-02-24 10:30:18.705799 [DEBUG] mod_sofia.c:4943 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_NEW -> CS_INIT 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_INIT (Cur 1 Tot 14) 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2348124523790@10.200.161.132) State INIT 2017-02-24 10:30:18.705799 [DEBUG] mod_sofia.c:92 sofia/external/%2B2348124523790@10.200.161.132 SOFIA INIT 2017-02-24 10:30:18.705799 [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 1487907020 1487907021 IN IP4 10.227.49.19 s=FreeSWITCH c=IN IP4 10.227.49.19 t=0 0 m=audio 21598 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 20664 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:30:18.705799 [DEBUG] switch_core_state_machine.c:40 sofia/external/%2B2348124523790@10.200.161.132 Standard INIT 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:48 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_INIT -> CS_ROUTING 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2348124523790@10.200.161.132) State INIT going to sleep 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_ROUTING (Cur 1 Tot 14) 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING 2017-02-24 10:30:18.705799 [DEBUG] mod_sofia.c:145 sofia/external/%2B2348124523790@10.200.161.132 SOFIA ROUTING 2017-02-24 10:30:18.705799 [DEBUG] switch_ivr_originate.c:67 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING going to sleep 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 14) 2017-02-24 10:30:18.705799 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [calling][0] 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA 2017-02-24 10:30:18.705799 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA going to sleep 2017-02-24 10:30:18.985797 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][183] 2017-02-24 10:30:18.985797 [DEBUG] sofia.c:7242 Remote SDP: v=0 o=- 14611505 14611505 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.35 t=0 0 a=sendrecv m=audio 33314 RTP/AVP 8 101 c=IN IP4 10.200.12.35 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:30:18.985797 [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:30:18.985797 [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:30:18.985797 [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:30:18.985797 [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:30:18.985797 [DEBUG] switch_core_media.c:4913 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-02-24 10:30:18.985797 [DEBUG] switch_core_media.c:4774 Set telephone-event payload to 101@8000 2017-02-24 10:30:18.985797 [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:30:18.985797 [DEBUG] switch_core_codec.c:111 sofia/external/%2B2348124523790@10.200.161.132 Original read codec set to PCMA:8 2017-02-24 10:30:18.985797 [DEBUG] switch_core_media.c:5117 Set telephone-event payload to 101@8000 2017-02-24 10:30:18.985797 [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:30:18.985797 [DEBUG] switch_core_media.c:7764 AUDIO RTP [sofia/external/%2B2348124523790@10.200.161.132] 10.227.49.19 port 21598 -> 10.200.12.35 port 33314 codec: 8 ms: 20 2017-02-24 10:30:18.985797 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms 2017-02-24 10:30:18.985797 [DEBUG] switch_core_media.c:8072 sofia/external/%2B2348124523790@10.200.161.132 Set 2833 dtmf send payload to 101 2017-02-24 10:30:18.985797 [DEBUG] switch_core_media.c:8079 sofia/external/%2B2348124523790@10.200.161.132 Set 2833 dtmf receive payload to 101 2017-02-24 10:30:18.985797 [DEBUG] switch_core_media.c:8102 sofia/external/%2B2348124523790@10.200.161.132 Set rtp dtmf delay to 40 2017-02-24 10:30:18.985797 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/%2B2348124523790@10.200.161.132! 2017-02-24 10:30:18.985797 [DEBUG] switch_channel.c:3473 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change DOWN -> EARLY 2017-02-24 10:30:18.985797 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/external/%2B2348124523790@10.200.161.132] 2017-02-24 10:30:18.985797 [DEBUG] switch_cpp.cpp:628 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE 2017-02-24 10:30:18.985797 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_SOFT_EXECUTE (Cur 1 Tot 14) 2017-02-24 10:30:18.985797 [DEBUG] switch_core_state_machine.c:656 (sofia/external/%2B2348124523790@10.200.161.132) State SOFT_EXECUTE 2017-02-24 10:30:18.985797 [DEBUG] mod_sofia.c:641 SOFIA SOFT_EXECUTE 2017-02-24 10:30:18.985797 [DEBUG] switch_core_state_machine.c:400 sofia/external/%2B2348124523790@10.200.161.132 Standard SOFT_EXECUTE 2017-02-24 10:30:18.985797 [DEBUG] switch_core_state_machine.c:656 (sofia/external/%2B2348124523790@10.200.161.132) State SOFT_EXECUTE going to sleep 2017-02-24 10:30:19.045797 [DEBUG] switch_rtp.c:7021 Correct audio ip/port confirmed. 2017-02-24 10:30:21.005822 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 2017-02-24 10:30:34.745768 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [completing][200] 2017-02-24 10:30:34.745768 [DEBUG] sofia.c:7239 Duplicate SDP v=0 o=- 14611505 14611505 IN IP4 TSS.LGTSS.LGTSS01.NG s=- c=IN IP4 10.200.12.35 t=0 0 a=sendrecv m=audio 33314 RTP/AVP 8 101 c=IN IP4 10.200.12.35 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:30:34.745768 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [ready][200] 2017-02-24 10:30:34.745768 [NOTICE] sofia.c:8295 Channel [sofia/external/%2B2348124523790@10.200.161.132] has been answered 2017-02-24 10:30:34.745768 [DEBUG] switch_channel.c:3772 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change EARLY -> ACTIVE 2017-02-24 10:30:34.905781 [DEBUG] switch_rtp.c:7021 Correct audio ip/port confirmed. 2017-02-24 10:30:35.325768 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/DI/U_know.wav 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_OUTGOING 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_CREATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_ORIGINATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CODEC 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CODEC 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_PROGRESS_MEDIA 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 PLAYBACK_START 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_ANSWER 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE 2017-02-24 10:30:38.345797 [INFO] switch_cpp.cpp:1377 PLAYBACK_STOP 2017-02-24 10:30:46.065797 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:30:46.065797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:31:06.065799 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:31:06.065799 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:31:26.085811 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:31:26.085811 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:31:26.085811 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:31:46.085798 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:31:46.085798 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:32:06.085793 [INFO] switch_cpp.cpp:1377 HEARTBEAT 2017-02-24 10:32:06.085793 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE 2017-02-24 10:32:25.925782 [NOTICE] sofia.c:1079 Hangup sofia/external/%2B2348124523790@10.200.161.132 [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_HANGUP (Cur 1 Tot 14) 2017-02-24 10:32:25.925782 [INFO] switch_cpp.cpp:1377 CHANNEL_HANGUP 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:850 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change ACTIVE -> HANGUP 2017-02-24 10:32:25.925782 [INFO] switch_cpp.cpp:1377 ObSession:hangupCause() = NORMAL_CLEARING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP 2017-02-24 10:32:25.925782 [DEBUG] mod_sofia.c:440 Channel sofia/external/%2B2348124523790@10.200.161.132 hanging up, cause: NORMAL_CLEARING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:60 sofia/external/%2B2348124523790@10.200.161.132 Standard HANGUP, cause: NORMAL_CLEARING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP going to sleep 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:619 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_HANGUP -> CS_REPORTING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_REPORTING (Cur 1 Tot 14) 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:174 sofia/external/%2B2348124523790@10.200.161.132 Standard REPORTING, cause: NORMAL_CLEARING 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING going to sleep 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:610 (sofia/external/%2B2348124523790@10.200.161.132) State Change CS_REPORTING -> CS_DESTROY 2017-02-24 10:32:25.925782 [DEBUG] switch_core_session.c:1712 Session 14 (sofia/external/%2B2348124523790@10.200.161.132) Locked, Waiting on external entities 2017-02-24 10:32:25.925782 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7f67100463c0 released. 2017-02-24 10:32:25.925782 [DEBUG] switch_cpp.cpp:1123 sofia/external/%2B2348124523790@10.200.161.132 destroy/unlink session from object 2017-02-24 10:32:25.925782 [NOTICE] switch_event.c:2141 Event Binding deleted for src/switch_cpp.cpp:ALL 2017-02-24 10:32:25.925782 [NOTICE] switch_core_session.c:1730 Session 14 (sofia/external/%2B2348124523790@10.200.161.132) Ended 2017-02-24 10:32:25.925782 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/%2B2348124523790@10.200.161.132 [CS_DESTROY] 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:741 (sofia/external/%2B2348124523790@10.200.161.132) Running State Change CS_DESTROY (Cur 0 Tot 14) 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY 2017-02-24 10:32:25.925782 [DEBUG] mod_sofia.c:345 sofia/external/%2B2348124523790@10.200.161.132 SOFIA DESTROY 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:181 sofia/external/%2B2348124523790@10.200.161.132 Standard DESTROY 2017-02-24 10:32:25.925782 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY going to sleep freeswitch@Peth-ivr3>