From Timothy, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. span style="color: cornflowerblue; font-weight: bold;"> 2017-02-24 10:47:01.145784 [NOTICE] switch_cpp.cpp:86 bound to all
  2. 2017-02-24 10:47:01.145784 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7f67080507a0 Connected.
  3. 2017-02-24 10:47:01.145784 [DEBUG] switch_ivr_originate.c:2143 Parsing global variables
  4. 2017-02-24 10:47:01.145784 [DEBUG] switch_ivr_originate.c:2664 Parsing session specific variables
  5. 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]
  6. 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
  7. 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)
  8. 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2348124523790@10.200.161.132) State INIT
  9. 2017-02-24 10:47:01.145784 [DEBUG] mod_sofia.c:92 sofia/external/%2B2348124523790@10.200.161.132 SOFIA INIT
  10. 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
  11. Local SDP:
  12. v=0
  13. o=FreeSWITCH 1487908379 1487908380 IN IP4 10.227.49.19
  14. s=FreeSWITCH
  15. c=IN IP4 10.227.49.19
  16. t=0 0
  17. m=audio 21242 RTP/AVP 102 9 0 8 104 101
  18. a=rtpmap:102 opus/48000/2
  19. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  20. a=rtpmap:9 G722/8000
  21. a=rtpmap:0 PCMU/8000
  22. a=rtpmap:8 PCMA/8000
  23. a=rtpmap:104 telephone-event/48000
  24. a=fmtp:104 0-16
  25. a=rtpmap:101 telephone-event/8000
  26. a=fmtp:101 0-16
  27. a=ptime:20
  28. a=sendrecv
  29. m=video 17228 RTP/AVP 103
  30. b=AS:1024
  31. a=rtpmap:103 VP8/90000
  32. a=rtcp-fb:103 ccm fir
  33. a=rtcp-fb:103 ccm tmmbr
  34. a=rtcp-fb:103 nack
  35. a=rtcp-fb:103 nack pli
  36.  
  37. 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:40 sofia/external/%2B2348124523790@10.200.161.132 Standard INIT
  38. 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
  39. 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
  40. 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)
  41. 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2348124523790@10.200.161.132) State ROUTING
  42. 2017-02-24 10:47:01.145784 [DEBUG] mod_sofia.c:145 sofia/external/%2B2348124523790@10.200.161.132 SOFIA ROUTING
  43. 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
  44. 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
  45. send 1419 bytes to udp/[10.200.161.132]:5060 at 10:47:01.163379:
  46.    ------------------------------------------------------------------------
  47.    INVITE sip:+2348124523790@10.200.161.132 SIP/2.0
  48.    Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB
  49.    Max-Forwards: 70
  50.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  51. 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)
  52.    To: <sip:+2348124523790@10.200.161.132>
  53.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  54.    CSeq: 103628746 INVITE
  55.    Contact: <sip:mod_sofia@10.227.47.13:5080>
  56.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170203T004705Z~1aadfc0fa4~64bit
  57.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  58.    Supported: timer, path, replaces
  59.    Allow-Events: talk, hold, conference, refer
  60.    Privacy: none
  61.    Content-Type: application/sdp
  62.    Content-Disposition: session
  63.    Content-Length: 621
  64. 2017-02-24 10:47:01.145784 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2348124523790@10.200.161.132) State CONSUME_MEDIA
  65. 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
  66.    X-FS-Support: update_display,send_info
  67.    P-Asserted-Identity: <sip:+2340802156106@10.227.47.13>
  68.  
  69.    v=0
  70.    o=FreeSWITCH 1487908379 1487908380 IN IP4 10.227.49.19
  71.    s=FreeSWITCH
  72.    c=IN IP4 10.227.49.19
  73.    t=0 0
  74.    m=audio 21242 RTP/AVP 102 9 0 8 104 101
  75.    a=rtpmap:102 opus/48000/2
  76.    a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  77.    a=rtpmap:9 G722/8000
  78.    a=rtpmap:0 PCMU/8000
  79.    a=rtpmap:8 PCMA/8000
  80.    a=rtpmap:104 telephone-event/48000
  81.    a=fmtp:104 0-16
  82.    a=rtpmap:101 telephone-event/8000
  83.    a=fmtp:101 0-16
  84.    a=ptime:20
  85.    m=video 17228 RTP/AVP 103
  86.    b=AS:1024
  87.    a=rtpmap:103 VP8/90000
  88.    a=rtcp-fb:103 ccm fir
  89.    a=rtcp-fb:103 ccm tmmbr
  90.    a=rtcp-fb:103 nack
  91.    a=rtcp-fb:103 nack pli
  92.    ------------------------------------------------------------------------
  93. 2017-02-24 10:47:01.145784 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [calling][0]
  94. recv 311 bytes from udp/[10.200.161.132]:5060 at 10:47:01.184955:
  95.    ------------------------------------------------------------------------
  96.    SIP/2.0 100 Trying
  97.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  98.    To: <sip:+2348124523790@10.200.161.132>
  99.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13
  100.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  101.    CSeq: 103628746 INVITE
  102.    Content-Length:    0
  103.  
  104.    ------------------------------------------------------------------------
  105. recv 862 bytes from udp/[10.200.161.132]:5060 at 10:47:01.455010:
  106.    ------------------------------------------------------------------------
  107.    SIP/2.0 183 Session Progress
  108.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  109.    To: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  110.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13
  111.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  112.    CSeq: 103628746 INVITE
  113.    Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE
  114.    Content-Type: application/sdp
  115.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  116.    Content-Length:  394
  117.  
  118.    v=0
  119.    o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG
  120.    s=-
  121.    c=IN IP4 10.200.12.31
  122.    t=0 0
  123.    a=sendrecv
  124.    m=audio 12674 RTP/AVP 8 101
  125.    c=IN IP4 10.200.12.31
  126.    a=rtpmap:8 PCMA/8000
  127.    a=rtpmap:101 telephone-event/8000
  128.    a=fmtp:101 0-15
  129.    a=maxptime:40
  130.    m=video 0 RTP/AVP 103
  131.    b=AS:1024
  132.    a=rtpmap:103 VP8/90000
  133.    a=rtcp-fb:103 ccm fir
  134.    a=rtcp-fb:103 ccm tmmbr
  135.    a=rtcp-fb:103 nack
  136.    a=rtcp-fb:103 nack pli
  137.    ------------------------------------------------------------------------
  138. 2017-02-24 10:47:01.445797 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][183]
  139. 2017-02-24 10:47:01.445797 [DEBUG] sofia.c:7242 Remote SDP:
  140. v=0
  141. o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG
  142. s=-
  143. c=IN IP4 10.200.12.31
  144. t=0 0
  145. a=sendrecv
  146. m=audio 12674 RTP/AVP 8 101
  147. c=IN IP4 10.200.12.31
  148. a=rtpmap:8 PCMA/8000
  149. a=rtpmap:101 telephone-event/8000
  150. a=fmtp:101 0-15
  151. a=maxptime:40
  152. m=video 0 RTP/AVP 103
  153. b=AS:1024
  154.  
  155. 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]
  156. 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]
  157. 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]
  158. 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]
  159. 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
  160. 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:4774 Set telephone-event payload to 101@8000
  161. 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
  162. 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
  163. 2017-02-24 10:47:01.445797 [DEBUG] switch_core_media.c:5117 Set telephone-event payload to 101@8000
  164. 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
  165. 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
  166. 2017-02-24 10:47:01.445797 [DEBUG] switch_rtp.c:3888 Starting timer [soft] 160 bytes per 20ms
  167. 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
  168. 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
  169. 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
  170. 2017-02-24 10:47:01.445797 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/%2B2348124523790@10.200.161.132!
  171. 2017-02-24 10:47:01.445797 [DEBUG] switch_channel.c:3473 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change DOWN -> EARLY
  172. 2017-02-24 10:47:01.445797 [DEBUG] switch_ivr_originate.c:3691 Originate Resulted in Success: [sofia/external/%2B2348124523790@10.200.161.132]
  173. 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
  174. 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)
  175. 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:656 (sofia/external/%2B2348124523790@10.200.161.132) State SOFT_EXECUTE
  176. 2017-02-24 10:47:01.445797 [DEBUG] mod_sofia.c:641 SOFIA SOFT_EXECUTE
  177. 2017-02-24 10:47:01.445797 [DEBUG] switch_core_state_machine.c:400 sofia/external/%2B2348124523790@10.200.161.132 Standard SOFT_EXECUTE
  178. 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
  179. 2017-02-24 10:47:01.505710 [DEBUG] switch_rtp.c:7021 Correct audio ip/port confirmed.
  180. 2017-02-24 10:47:03.445798 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  181. recv 377 bytes from udp/[10.200.161.132]:5060 at 10:47:06.213928:
  182.    ------------------------------------------------------------------------
  183.    SIP/2.0 180 Ringing
  184.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  185.    To: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  186.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13
  187.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  188.    CSeq: 103628746 INVITE
  189.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  190.    Content-Length:    0
  191.  
  192.    ------------------------------------------------------------------------
  193. 2017-02-24 10:47:06.205797 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [proceeding][180]
  194. 2017-02-24 10:47:06.205797 [NOTICE] sofia.c:7340 Ring-Ready sofia/external/%2B2348124523790@10.200.161.132!
  195. 2017-02-24 10:47:06.205797 [DEBUG] switch_channel.c:3345 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change EARLY -> RINGING
  196. 2017-02-24 10:47:12.185797 [DEBUG] switch_rtp.c:7257 RTP RECV DTMF *:560
  197. 2017-02-24 10:47:12.185797 [INFO] switch_channel.c:515 RECV DTMF *:560
  198. 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
  199. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_OUTGOING
  200. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE
  201. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CREATE
  202. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_ORIGINATE
  203. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE
  204. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE
  205. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CODEC
  206. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CODEC
  207. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_PROGRESS_MEDIA
  208. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE
  209. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_STATE
  210. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 PLAYBACK_START
  211. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_PROGRESS
  212. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE
  213. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  214. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  215. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 DTMF
  216. 2017-02-24 10:47:12.185797 [INFO] switch_cpp.cpp:1377 PLAYBACK_STOP
  217. recv 873 bytes from udp/[10.200.161.132]:5060 at 10:47:17.515126:
  218.    ------------------------------------------------------------------------
  219.    SIP/2.0 200 OK
  220.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  221.    To: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  222.    Via: SIP/2.0/UDP  10.227.47.13:5080;rport;branch=z9hG4bK4QZrKy57SDXQB;received=10.227.47.13
  223.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  224.    CSeq: 103628746 INVITE
  225.    Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,PRACK,UPDATE
  226.    Accept: application/sdp
  227.    Content-Type: application/sdp
  228.    Contact: <sip:10.200.161.132:5060;transport=UDP>
  229.    Content-Length:  394
  230.  
  231.    v=0
  232.    o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG
  233.    s=-
  234.    c=IN IP4 10.200.12.31
  235.    t=0 0
  236.    a=sendrecv
  237.    m=audio 12674 RTP/AVP 8 101
  238.    c=IN IP4 10.200.12.31
  239.    a=rtpmap:8 PCMA/8000
  240.    a=rtpmap:101 telephone-event/8000
  241.    a=fmtp:101 0-15
  242.    a=maxptime:40
  243.    m=video 0 RTP/AVP 103
  244.    b=AS:1024
  245.    a=rtpmap:103 VP8/90000
  246.    a=rtcp-fb:103 ccm fir
  247.    a=rtcp-fb:103 ccm tmmbr
  248.    a=rtcp-fb:103 nack
  249.    a=rtcp-fb:103 nack pli
  250.    ------------------------------------------------------------------------
  251. 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [completing][200]
  252. 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7239 Duplicate SDP
  253. v=0
  254. o=- 16724490 16724490 IN IP4 TSS.LGTSS.LGTSS01.NG
  255. s=-
  256. c=IN IP4 10.200.12.31
  257. t=0 0
  258. a=sendrecv
  259. m=audio 12674 RTP/AVP 8 101
  260. c=IN IP4 10.200.12.31
  261. a=rtpmap:8 PCMA/8000
  262. a=rtpmap:101 telephone-event/8000
  263. a=fmtp:101 0-15
  264. a=maxptime:40
  265. m=video 0 RTP/AVP 103
  266. b=AS:1024
  267.  
  268. send 390 bytes to udp/[10.200.161.132]:5060 at 10:47:17.516804:
  269.    ------------------------------------------------------------------------
  270.    ACK sip:10.200.161.132:5060;transport=UDP SIP/2.0
  271.    Via: SIP/2.0/UDP 10.227.47.13:5080;rport;branch=z9hG4bK50rHNSpBQpKaQ
  272.    Max-Forwards: 70
  273.    From: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  274.    To: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  275.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  276.    CSeq: 103628746 ACK
  277.    Contact: <sip:mod_sofia@10.227.47.13:5080>
  278.    Content-Length: 0
  279.  
  280.    ------------------------------------------------------------------------
  281. 2017-02-24 10:47:17.505799 [DEBUG] sofia.c:7232 Channel sofia/external/%2B2348124523790@10.200.161.132 entering state [ready][200]
  282. 2017-02-24 10:47:17.505799 [NOTICE] sofia.c:8295 Channel [sofia/external/%2B2348124523790@10.200.161.132] has been answered
  283. 2017-02-24 10:47:17.505799 [INFO] switch_cpp.cpp:1377 CHANNEL_ANSWER
  284. 2017-02-24 10:47:17.505799 [DEBUG] switch_channel.c:3772 (sofia/external/%2B2348124523790@10.200.161.132) Callstate Change RINGING -> ACTIVE
  285. 2017-02-24 10:47:17.505799 [INFO] switch_cpp.cpp:1377 CHANNEL_CALLSTATE
  286. 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  287. 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  288. 2017-02-24 10:47:26.365797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  289. 2017-02-24 10:47:46.365769 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  290. 2017-02-24 10:47:46.365769 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  291. 2017-02-24 10:48:06.385797 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  292. 2017-02-24 10:48:06.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  293. 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  294. 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  295. 2017-02-24 10:48:26.385797 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  296. 2017-02-24 10:48:46.385799 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  297. 2017-02-24 10:48:46.385799 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  298. 2017-02-24 10:49:06.405796 [INFO] switch_cpp.cpp:1377 HEARTBEAT
  299. 2017-02-24 10:49:06.405796 [INFO] switch_cpp.cpp:1377 RE_SCHEDULE
  300. recv 363 bytes from udp/[10.200.161.132]:5060 at 10:49:08.738429:
  301.    ------------------------------------------------------------------------
  302.    BYE sip:mod_sofia@10.227.47.13:5080 SIP/2.0
  303.    From: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  304.    To: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  305.    Max-Forwards: 70
  306.    Via: SIP/2.0/UDP  10.200.161.132:5060;branch=z9hG4bK00000025204689020616
  307.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  308.    CSeq: 1 BYE
  309.    Reason: Q.850;cause=16
  310.    Content-Length:    0
  311.  
  312.    ------------------------------------------------------------------------
  313. 2017-02-24 10:49:08.725807 [NOTICE] sofia.c:1079 Hangup sofia/external/%2B2348124523790@10.200.161.132 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]
  314. 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)
  315. 2017-02-24 10:49:08.725807 [INFO] switch_cpp.cpp:1377 CHANNEL_HANGUP
  316. 2017-02-24 10:49:08.725807 [INFO] switch_cpp.cpp:1377 ObSession:hangupCause() = NORMAL_CLEARING
  317. send 491 bytes to udp/[10.200.161.132]:5060 at 10:49:08.740153:
  318.    ------------------------------------------------------------------------
  319. 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
  320.    SIP/2.0 200 OK
  321.    Via: SIP/2.0/UDP  10.200.161.132:5060;branch=z9hG4bK00000025204689020616
  322.    From: <sip:+2348124523790@10.200.161.132>;tag=0259576292
  323.    To: "" <sip:+2340802156106@10.227.47.13>;tag=Sm4Q9cUN6rpXg
  324.    Call-ID: 0813419b-7519-1235-a08b-000c29abbe7e
  325.    CSeq: 1 BYE
  326.    User-Agent: FreeSWITCH-mod_sofia/1.9.0+git~20170203T004705Z~1aadfc0fa4~64bit
  327.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  328.    Supported: timer, path, replaces
  329.    Content-Length: 0
  330.  
  331.    ------------------------------------------------------------------------
  332. 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2348124523790@10.200.161.132) State HANGUP
  333. 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
  334. 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
  335. 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
  336. 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
  337. 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)
  338. 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2348124523790@10.200.161.132) State REPORTING
  339. 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
  340. 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
  341. 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
  342. 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
  343. 2017-02-24 10:49:08.725807 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7f67080507a0 released.
  344. 2017-02-24 10:49:08.725807 [DEBUG] switch_cpp.cpp:1123 sofia/external/%2B2348124523790@10.200.161.132 destroy/unlink session from object
  345. 2017-02-24 10:49:08.725807 [NOTICE] switch_event.c:2141 Event Binding deleted for src/switch_cpp.cpp:ALL
  346. 2017-02-24 10:49:08.725807 [NOTICE] switch_core_session.c:1730 Session 15 (sofia/external/%2B2348124523790@10.200.161.132) Ended
  347. 2017-02-24 10:49:08.725807 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/%2B2348124523790@10.200.161.132 [CS_DESTROY]
  348. 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)
  349. 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2348124523790@10.200.161.132) State DESTROY
  350. 2017-02-24 10:49:08.725807 [DEBUG] mod_sofia.c:345 sofia/external/%2B2348124523790@10.200.161.132 SOFIA DESTROY
  351. 2017-02-24 10:49:08.725807 [DEBUG] switch_core_state_machine.c:181 sofia/external/%2B2348124523790@10.200.161.132 Standard DESTROY
  352. 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
  353. freeswitch@Peth-ivr3>