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>