From daptim, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1.  
  2. freeswitch@Peth-ivr3>
  3. freeswitch@Peth-ivr3> bgapi originate {sip_cid_type=pid,service_type=UKNOW,origination_caller_id_number=+2348023565181,igonre_early_media=true}sofia/external/sip:+2347016922641@10.200.161.132 '&lua(/usr/local/freeswitch/scripts/obsession.lua)'
  4. +OK Job-UUID: 70864693-a139-4f24-a7b4-9cb8f7bb5e23
  5.  
  6. 2017-04-11 15:51:04.611519 [DEBUG] switch_ivr_originate.c:2147 Parsing global variables
  7. 2017-04-11 15:51:04.611519 [NOTICE] switch_channel.c:1104 New Channel sofia/external/%2B2347016922641@10.200.161.132 [08aa8a4b-838d-4a0f-8d24-0a3b74a9d9b5]
  8. 2017-04-11 15:51:04.611519 [DEBUG] mod_sofia.c:5011 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_NEW -> CS_INIT
  9. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_INIT (Cur 1 Tot 2)
  10. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2347016922641@10.200.161.132) State INIT
  11. 2017-04-11 15:51:04.611519 [DEBUG] mod_sofia.c:93 sofia/external/%2B2347016922641@10.200.161.132 SOFIA INIT
  12. 2017-04-11 15:51:04.611519 [DEBUG] sofia_glue.c:1298 sofia/external/%2B2347016922641@10.200.161.132 sending invite version: 1.9.0 git aaa26c6 2017-04-10 20:24:05Z 64bit
  13. Local SDP:
  14. v=0
  15. o=FreeSWITCH 1491890242 1491890243 IN IP4 10.227.49.19
  16. s=FreeSWITCH
  17. c=IN IP4 10.227.49.19
  18. t=0 0
  19. m=audio 32022 RTP/AVP 102 9 0 8 104 101
  20. a=rtpmap:102 opus/48000/2
  21. a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40
  22. a=rtpmap:9 G722/8000
  23. a=rtpmap:0 PCMU/8000
  24. a=rtpmap:8 PCMA/8000
  25. a=rtpmap:104 telephone-event/48000
  26. a=fmtp:104 0-16
  27. a=rtpmap:101 telephone-event/8000
  28. a=fmtp:101 0-16
  29. a=ptime:20
  30. a=sendrecv
  31. m=video 28768 RTP/AVP 103
  32. b=AS:1024
  33. a=rtpmap:103 VP8/90000
  34. a=sendrecv
  35. a=rtcp-fb:103 ccm fir
  36. a=rtcp-fb:103 ccm tmmbr
  37. a=rtcp-fb:103 nack
  38. a=rtcp-fb:103 nack pli
  39.  
  40. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:40 sofia/external/%2B2347016922641@10.200.161.132 Standard INIT
  41. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:48 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_INIT -> CS_ROUTING
  42. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:627 (sofia/external/%2B2347016922641@10.200.161.132) State INIT going to sleep
  43. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_ROUTING (Cur 1 Tot 2)
  44. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2347016922641@10.200.161.132) State ROUTING
  45. 2017-04-11 15:51:04.611519 [DEBUG] mod_sofia.c:154 sofia/external/%2B2347016922641@10.200.161.132 SOFIA ROUTING
  46. 2017-04-11 15:51:04.611519 [DEBUG] switch_ivr_originate.c:67 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  47. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:643 (sofia/external/%2B2347016922641@10.200.161.132) State ROUTING going to sleep
  48. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 2)
  49. 2017-04-11 15:51:04.611519 [DEBUG] sofia.c:7247 Channel sofia/external/%2B2347016922641@10.200.161.132 entering state [calling][0]
  50. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2347016922641@10.200.161.132) State CONSUME_MEDIA
  51. 2017-04-11 15:51:04.611519 [DEBUG] switch_core_state_machine.c:662 (sofia/external/%2B2347016922641@10.200.161.132) State CONSUME_MEDIA going to sleep
  52. 2017-04-11 15:51:07.171511 [DEBUG] sofia.c:7247 Channel sofia/external/%2B2347016922641@10.200.161.132 entering state [proceeding][183]
  53. 2017-04-11 15:51:07.171511 [DEBUG] sofia.c:7257 Remote SDP:
  54. v=0
  55. o=- 13642565 13642565 IN IP4 TSS.LGTSS.LGTSS01.NG
  56. s=-
  57. c=IN IP4 10.200.12.22
  58. t=0 0
  59. a=sendrecv
  60. m=audio 5040 RTP/AVP 8 101
  61. c=IN IP4 10.200.12.22
  62. a=rtpmap:8 PCMA/8000
  63. a=rtpmap:101 telephone-event/8000
  64. a=fmtp:101 0-15
  65. a=maxptime:40
  66. m=video 0 RTP/AVP 103
  67. b=AS:1024
  68.  
  69. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5102 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[opus:116:48000:20:0:1]
  70. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5102 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[G722:9:8000:20:64000:1]
  71. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5102 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMU:0:8000:20:64000:1]
  72. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5102 Audio Codec Compare [PCMA:8:8000:40:64000:1]/[PCMA:8:8000:20:64000:1]
  73. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5157 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  74. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5018 Set telephone-event payload to 101@8000
  75. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:3425 Set Codec sofia/external/%2B2347016922641@10.200.161.132 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  76. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_codec.c:111 sofia/external/%2B2347016922641@10.200.161.132 Original read codec set to PCMA:8
  77. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5361 Set telephone-event payload to 101@8000
  78. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:5420 sofia/external/%2B2347016922641@10.200.161.132 Set 2833 dtmf send payload to 101 recv payload to 101
  79. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:8137 AUDIO RTP [sofia/external/%2B2347016922641@10.200.161.132] 10.227.49.19 port 32022 -> 10.200.12.22 port 5040 codec: 8 ms: 20
  80. 2017-04-11 15:51:07.171511 [DEBUG] switch_rtp.c:4096 Starting timer [soft] 160 bytes per 20ms
  81. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:8440 sofia/external/%2B2347016922641@10.200.161.132 Set 2833 dtmf send payload to 101
  82. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:8447 sofia/external/%2B2347016922641@10.200.161.132 Set 2833 dtmf receive payload to 101
  83. 2017-04-11 15:51:07.171511 [DEBUG] switch_core_media.c:8470 sofia/external/%2B2347016922641@10.200.161.132 Set rtp dtmf delay to 40
  84. 2017-04-11 15:51:07.171511 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/%2B2347016922641@10.200.161.132!
  85. 2017-04-11 15:51:07.171511 [DEBUG] switch_channel.c:3481 (sofia/external/%2B2347016922641@10.200.161.132) Callstate Change DOWN -> EARLY
  86. 2017-04-11 15:51:07.191511 [DEBUG] switch_ivr_originate.c:3695 Originate Resulted in Success: [sofia/external/%2B2347016922641@10.200.161.132]
  87. 2017-04-11 15:51:07.191511 [INFO] switch_channel.c:3130 sofia/external/%2B2347016922641@10.200.161.132 Flipping CID from "" <+2348023565181> to "Outbound Call" <+2347016922641>
  88. 2017-04-11 15:51:07.191511 [DEBUG] mod_commands.c:4844 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
  89. 2017-04-11 15:51:07.191511 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_EXECUTE (Cur 1 Tot 2)
  90. 2017-04-11 15:51:07.191511 [DEBUG] switch_core_state_machine.c:650 (sofia/external/%2B2347016922641@10.200.161.132) State EXECUTE
  91. 2017-04-11 15:51:07.191511 [DEBUG] mod_sofia.c:209 sofia/external/%2B2347016922641@10.200.161.132 SOFIA EXECUTE
  92. 2017-04-11 15:51:07.191511 [DEBUG] switch_core_state_machine.c:328 sofia/external/%2B2347016922641@10.200.161.132 Standard EXECUTE
  93. EXECUTE sofia/external/%2B2347016922641@10.200.161.132 lua(/usr/local/freeswitch/scripts/obsession.lua)
  94. 2017-04-11 15:51:07.191511 [NOTICE] switch_cpp.cpp:86 bound to all
  95. 2017-04-11 15:51:07.331525 [DEBUG] freeswitch_lua.cpp:372 DBH handle 0x7f8ac40633e0 Connected.
  96. 2017-04-11 15:51:07.331525 [DEBUG] switch_cpp.cpp:755 CoreSession::setVariable('ObCause', '')
  97. 2017-04-11 15:51:08.131510 [DEBUG] switch_rtp.c:7243 Correct audio ip/port confirmed.
  98. 2017-04-11 15:51:08.131510 [DEBUG] sofia.c:7247 Channel sofia/external/%2B2347016922641@10.200.161.132 entering state [proceeding][180]
  99. 2017-04-11 15:51:08.131510 [NOTICE] sofia.c:7355 Ring-Ready sofia/external/%2B2347016922641@10.200.161.132!
  100. 2017-04-11 15:51:08.131510 [DEBUG] switch_channel.c:3353 (sofia/external/%2B2347016922641@10.200.161.132) Callstate Change EARLY -> RINGING
  101. EXECUTE sofia/external/%2B2347016922641@10.200.161.132 avmd(start)
  102. 2017-04-11 15:51:08.331592 [WARNING] mod_avmd.c:1482 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
  103. 2017-04-11 15:51:08.331592 [INFO] mod_avmd.c:1112 Avmd dynamic configuration: debug [0], report_status [1], fast_math [0], require_continuous_streak [1], sample_n_continuous_streak [3], sample_n_to_skip [0], require_continuous_streak_amp [1], sample_n_continuous_streak_amp [3], simplified_estimation [1], inbound_channel [0], outbound_channel [1], detection_mode [2], detectors_n [0], detectors_lagged_n [0]
  104. 2017-04-11 15:51:08.331592 [INFO] mod_avmd.c:609 Avmd session initialized, [8000] samples/s
  105. 2017-04-11 15:51:08.331592 [DEBUG] switch_core_media_bug.c:841 Attaching BUG to sofia/external/%2B2347016922641@10.200.161.132
  106. 2017-04-11 15:51:08.331592 [INFO] mod_avmd.c:1410 Avmd on channel [sofia/external/%2B2347016922641@10.200.161.132] started!
  107. 2017-04-11 15:51:08.391579 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  108. 2017-04-11 15:51:08.431507 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
  109. 2017-04-11 15:51:22.711511 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/DI/U_know.wav
  110. 2017-04-11 15:51:23.091510 [DEBUG] switch_core_io.c:780 Engaging Read Buffer at 320 bytes vs 80
  111. 2017-04-11 15:51:23.231512 [INFO] sofia.c:1337 sofia/external/%2B2347016922641@10.200.161.132 Update Caller ID to "Outbound Call" <7016922641>
  112. 2017-04-11 15:51:23.231512 [DEBUG] sofia.c:7247 Channel sofia/external/%2B2347016922641@10.200.161.132 entering state [completing][200]
  113. 2017-04-11 15:51:23.231512 [DEBUG] sofia.c:7254 Duplicate SDP
  114. v=0
  115. o=- 13642565 13642565 IN IP4 TSS.LGTSS.LGTSS01.NG
  116. s=-
  117. c=IN IP4 10.200.12.22
  118. t=0 0
  119. a=sendrecv
  120. m=audio 5040 RTP/AVP 8 101
  121. c=IN IP4 10.200.12.22
  122. a=rtpmap:8 PCMA/8000
  123. a=rtpmap:101 telephone-event/8000
  124. a=fmtp:101 0-15
  125. a=maxptime:40
  126. m=video 0 RTP/AVP 103
  127. b=AS:1024
  128.  
  129. 2017-04-11 15:51:23.251511 [DEBUG] sofia.c:7247 Channel sofia/external/%2B2347016922641@10.200.161.132 entering state [ready][200]
  130. 2017-04-11 15:51:23.251511 [NOTICE] sofia.c:8324 Channel [sofia/external/%2B2347016922641@10.200.161.132] has been answered
  131. 2017-04-11 15:51:23.251511 [DEBUG] switch_channel.c:3780 (sofia/external/%2B2347016922641@10.200.161.132) Callstate Change RINGING -> ACTIVE
  132. 2017-04-11 15:51:23.291509 [DEBUG] switch_rtp.c:7243 Correct audio ip/port confirmed.
  133. EXECUTE sofia/external/%2B2347016922641@10.200.161.132 avmd(stop)
  134. 2017-04-11 15:51:25.731510 [WARNING] mod_avmd.c:1482 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
  135. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_media_bug.c:1216 Removing BUG from sofia/external/%2B2347016922641@10.200.161.132
  136. 2017-04-11 15:51:25.731510 [DEBUG] switch_cpp.cpp:731 CoreSession::hangup
  137. 2017-04-11 15:51:25.731510 [NOTICE] switch_cpp.cpp:733 Hangup sofia/external/%2B2347016922641@10.200.161.132 [CS_EXECUTE] [NORMAL_CLEARING]
  138. 2017-04-11 15:51:25.731510 [DEBUG] switch_cpp.cpp:1122 sofia/external/%2B2347016922641@10.200.161.132 destroy/unlink session from object
  139. 2017-04-11 15:51:25.731510 [DEBUG] freeswitch_lua.cpp:401 DBH handle 0x7f8ac40633e0 released.
  140. 2017-04-11 15:51:25.731510 [NOTICE] switch_event.c:2141 Event Binding deleted for src/switch_cpp.cpp:ALL
  141. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_session.c:2884 sofia/external/%2B2347016922641@10.200.161.132 skip receive message [PHONE_EVENT] (channel is hungup already)
  142. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:650 (sofia/external/%2B2347016922641@10.200.161.132) State EXECUTE going to sleep
  143. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_HANGUP (Cur 1 Tot 2)
  144. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:850 (sofia/external/%2B2347016922641@10.200.161.132) Callstate Change ACTIVE -> HANGUP
  145. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2347016922641@10.200.161.132) State HANGUP
  146. 2017-04-11 15:51:25.731510 [DEBUG] mod_sofia.c:449 Channel sofia/external/%2B2347016922641@10.200.161.132 hanging up, cause: NORMAL_CLEARING
  147. 2017-04-11 15:51:25.731510 [DEBUG] mod_sofia.c:502 Sending BYE to sofia/external/%2B2347016922641@10.200.161.132
  148. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:60 sofia/external/%2B2347016922641@10.200.161.132 Standard HANGUP, cause: NORMAL_CLEARING
  149. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:852 (sofia/external/%2B2347016922641@10.200.161.132) State HANGUP going to sleep
  150. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:619 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_HANGUP -> CS_REPORTING
  151. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:584 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_REPORTING (Cur 1 Tot 2)
  152. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2347016922641@10.200.161.132) State REPORTING
  153. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:174 sofia/external/%2B2347016922641@10.200.161.132 Standard REPORTING, cause: NORMAL_CLEARING
  154. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:938 (sofia/external/%2B2347016922641@10.200.161.132) State REPORTING going to sleep
  155. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:610 (sofia/external/%2B2347016922641@10.200.161.132) State Change CS_REPORTING -> CS_DESTROY
  156. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_session.c:1712 Session 2 (sofia/external/%2B2347016922641@10.200.161.132) Locked, Waiting on external entities
  157. 2017-04-11 15:51:25.731510 [NOTICE] switch_core_session.c:1730 Session 2 (sofia/external/%2B2347016922641@10.200.161.132) Ended
  158. 2017-04-11 15:51:25.731510 [NOTICE] switch_core_session.c:1734 Close Channel sofia/external/%2B2347016922641@10.200.161.132 [CS_DESTROY]
  159. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:741 (sofia/external/%2B2347016922641@10.200.161.132) Running State Change CS_DESTROY (Cur 0 Tot 2)
  160. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2347016922641@10.200.161.132) State DESTROY
  161. 2017-04-11 15:51:25.731510 [DEBUG] mod_sofia.c:354 sofia/external/%2B2347016922641@10.200.161.132 SOFIA DESTROY
  162. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:181 sofia/external/%2B2347016922641@10.200.161.132 Standard DESTROY
  163. 2017-04-11 15:51:25.731510 [DEBUG] switch_core_state_machine.c:751 (sofia/external/%2B2347016922641@10.200.161.132) State DESTROY going to sleep
  164. freeswitch@Peth-ivr3>
  165.