From Devang, 5 Years ago, written in FreeSWITCH.
  1. freeswitch@astpp03>
  2. 2018-10-03 02:26:01.401614 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  3. 2018-10-03 02:26:01.401614 [NOTICE] switch_channel.c:1104 New Channel sofia/default/919033769913@demo.inextrix.com:5061 [b2d5766c-c510-4ab1-9061-59d9e5db1d69]
  4. 2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:4819 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_NEW -> CS_INIT
  5. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_INIT (Cur 1 Tot 16)
  6. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:627 (sofia/default/919033769913@demo.inextrix.com:5061) State INIT
  7. 2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:90 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA INIT
  8. 2018-10-03 02:26:01.401614 [DEBUG] sofia_glue.c:1295 sofia/default/919033769913@demo.inextrix.com:5061 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  9. Local SDP:
  10. v=0
  11. o=FreeSWITCH 1538513887 1538513888 IN IP4 81.95.124.27
  12. s=FreeSWITCH
  13. c=IN IP4 81.95.124.27
  14. t=0 0
  15. m=audio 19674 RTP/AVP 0 8 9 18 101
  16. a=rtpmap:0 PCMU/8000
  17. a=rtpmap:8 PCMA/8000
  18. a=rtpmap:9 G722/8000
  19. a=rtpmap:18 G729/8000
  20. a=rtpmap:101 telephone-event/8000
  21. a=fmtp:101 0-16
  22. a=ptime:20
  23. a=sendrecv
  24.  
  25. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:40 sofia/default/919033769913@demo.inextrix.com:5061 Standard INIT
  26. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:48 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_INIT -> CS_ROUTING
  27. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:627 (sofia/default/919033769913@demo.inextrix.com:5061) State INIT going to sleep
  28. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 1 Tot 16)
  29. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING
  30. 2018-10-03 02:26:01.401614 [DEBUG] mod_sofia.c:143 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA ROUTING
  31. 2018-10-03 02:26:01.401614 [DEBUG] switch_ivr_originate.c:67 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  32. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING going to sleep
  33. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 16)
  34. 2018-10-03 02:26:01.401614 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [calling][0]
  35. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:662 (sofia/default/919033769913@demo.inextrix.com:5061) State CONSUME_MEDIA
  36. 2018-10-03 02:26:01.401614 [DEBUG] switch_core_state_machine.c:662 (sofia/default/919033769913@demo.inextrix.com:5061) State CONSUME_MEDIA going to sleep
  37. 2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [completing][200]
  38. 2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7094 Remote SDP:
  39. v=0
  40. o=root 1284469274 1284469274 IN IP4 159.203.131.36
  41. s=Asterisk PBX 13.8.0
  42. c=IN IP4 159.203.131.36
  43. t=0 0
  44. m=audio 18402 RTP/AVP 0 8 101
  45. a=rtpmap:0 PCMU/8000
  46. a=rtpmap:8 PCMA/8000
  47. a=rtpmap:101 telephone-event/8000
  48. a=fmtp:101 0-16
  49. a=maxptime:150
  50.  
  51. 2018-10-03 02:26:01.641624 [DEBUG] sofia.c:7084 Channel sofia/default/919033769913@demo.inextrix.com:5061 entering state [ready][200]
  52. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  53. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  54. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  55. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G722:9:8000:20:64000:1]
  56. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  57. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  58. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  59. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  60. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G722:9:8000:20:64000:1]
  61. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  62. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  63. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/919033769913@demo.inextrix.com:5061 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  64. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_codec.c:111 sofia/default/919033769913@demo.inextrix.com:5061 Original read codec set to PCMU:0
  65. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  66. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:4767 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101 recv payload to 101
  67. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/919033769913@demo.inextrix.com:5061] 81.95.124.27 port 19674 -> 159.203.131.36 port 18402 codec: 0 ms: 20
  68. 2018-10-03 02:26:01.641624 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  69. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7179 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101
  70. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7186 sofia/default/919033769913@demo.inextrix.com:5061 Set 2833 dtmf receive payload to 101
  71. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_media.c:7209 sofia/default/919033769913@demo.inextrix.com:5061 Set rtp dtmf delay to 40
  72. 2018-10-03 02:26:01.641624 [INFO] switch_core_session.c:1619 sofia/default/919033769913@demo.inextrix.com:5061 setting session heartbeat to 10 second(s).
  73. 2018-10-03 02:26:01.641624 [NOTICE] sofia.c:8218 Channel [sofia/default/919033769913@demo.inextrix.com:5061] has been answered
  74. 2018-10-03 02:26:01.641624 [DEBUG] switch_channel.c:3773 (sofia/default/919033769913@demo.inextrix.com:5061) Callstate Change DOWN -> ACTIVE
  75. 2018-10-03 02:26:01.641624 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/919033769913@demo.inextrix.com:5061]
  76. 2018-10-03 02:26:01.641624 [DEBUG] switch_ivr.c:2165 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  77. 2018-10-03 02:26:01.641624 [NOTICE] switch_ivr.c:2172 Transfer sofia/default/919033769913@demo.inextrix.com:5061 to XML[0676338702@default]
  78. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 1 Tot 16)
  79. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING
  80. 2018-10-03 02:26:01.641624 [DEBUG] mod_sofia.c:143 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA ROUTING
  81. 2018-10-03 02:26:01.641624 [DEBUG] switch_core_state_machine.c:236 sofia/default/919033769913@demo.inextrix.com:5061 Standard ROUTING
  82. 2018-10-03 02:26:01.641624 [INFO] mod_dialplan_xml.c:637 Processing 919033769913 <919033769913>->0676338702 in context default
  83. 2018-10-03 02:26:01.661612 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5f40468e70 Connected.
  84. 2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [LOAD_CONF] Query :SELECT name,value FROM  tbl_settings WHERE group_title IN ('global')
  85. 2018-10-03 02:26:01.661612 [NOTICE] switch_cpp.cpp:1365 [Audio Conference] [xml_handler] Params:
  86. 'Event-Name: REQUEST_PARAMS
  87. Core-UUID: f4183a48-fe2b-4698-83d4-87d80b08381d
  88. FreeSWITCH-Hostname: astpp03
  89. FreeSWITCH-Switchname: astpp03
  90. FreeSWITCH-IPv4: 81.95.124.27
  91. FreeSWITCH-IPv6: %3A%3A1
  92. Event-Date-Local: 2018-10-03%2002%3A26%3A01
  93. Event-Date-GMT: Wed,%2003%20Oct%202018%2002%3A26%3A01%20GMT
  94. Event-Date-Timestamp: 1538533561641624
  95. Event-Calling-File: mod_dialplan_xml.c
  96. Event-Calling-Function: dialplan_xml_locate
  97. Event-Calling-Line-Number: 608
  98. Event-Sequence: 29753
  99. Channel-State: CS_ROUTING
  100. Channel-Call-State: ACTIVE
  101. Channel-State-Number: 2
  102. Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
  103. Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  104. Call-Direction: outbound
  105. Presence-Call-Direction: outbound
  106. Channel-HIT-Dialplan: false
  107. Channel-Call-UUID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  108. Answer-State: answered
  109. Channel-Read-Codec-Name: PCMU
  110. Channel-Read-Codec-Rate: 8000
  111. Channel-Read-Codec-Bit-Rate: 64000
  112. Channel-Write-Codec-Name: PCMU
  113. Channel-Write-Codec-Rate: 8000
  114. Channel-Write-Codec-Bit-Rate: 64000
  115. Caller-Direction: outbound
  116. Caller-Logical-Direction: outbound
  117. Caller-Dialplan: XML
  118. Caller-Caller-ID-Name: 919033769913
  119. Caller-Caller-ID-Number: 919033769913
  120. Caller-Orig-Caller-ID-Name: 919033769913
  121. Caller-Orig-Caller-ID-Number: 919033769913
  122. Caller-Callee-ID-Name: Outbound%20Call
  123. Caller-Callee-ID-Number: 919033769913
  124. Caller-Network-Addr: 159.203.131.36
  125. Caller-ANI: 919033769913
  126. Caller-Destination-Number: 0676338702
  127. Caller-Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  128. Caller-Source: src/switch_ivr_originate.c
  129. Caller-Transfer-Source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
  130. Caller-Context: default
  131. Caller-RDNIS: 919033769913
  132. Caller-Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
  133. Caller-Profile-Index: 2
  134. Caller-Profile-Created-Time: 1538533561641624
  135. Caller-Channel-Created-Time: 1538533561401614
  136. Caller-Channel-Answered-Time: 1538533561641624
  137. Caller-Channel-Progress-Time: 0
  138. Caller-Channel-Progress-Media-Time: 0
  139. Caller-Channel-Hangup-Time: 0
  140. Caller-Channel-Transfer-Time: 0
  141. Caller-Channel-Resurrect-Time: 0
  142. Caller-Channel-Bridged-Time: 0
  143. Caller-Channel-Last-Hold: 0
  144. Caller-Channel-Hold-Accum: 0
  145. Caller-Screen-Bit: true
  146. Caller-Privacy-Hide-Name: false
  147. Caller-Privacy-Hide-Number: false
  148. variable_direction: outbound
  149. variable_is_outbound: true
  150. variable_uuid: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  151. variable_session_id: 16
  152. variable_sip_profile_name: default
  153. variable_video_media_flow: sendrecv
  154. variable_audio_media_flow: sendrecv
  155. variable_channel_name: sofia/default/919033769913%40demo.inextrix.com%3A5061
  156. variable_sip_destination_url: sip%3A919033769913%40demo.inextrix.com%3A5061
  157. variable_hangup_after_bridge: true
  158. variable_ignore_early_media: true
  159. variable_conference_enforce_security: true
  160. variable_conference_enter_sound: conference/conf-entry_sound.wav
  161. variable_conference_exit_sound: conference/conf-exit_sound.wav
  162. variable_ann_name: 0
  163. variable_trunk_id: 3
  164. variable_plan_id: 1
  165. variable_prefix: 9
  166. variable_COST: 0.00000
  167. variable_c_cost: 0.00000
  168. variable_n_init_inc: 60
  169. variable_credit: 757.41913
  170. variable_type: customer
  171. variable_contact_id: 0
  172. variable_customer_id: 1
  173. variable_n_inc: 60
  174. variable_n_rate: 0.00000
  175. variable_n_acc: 1
  176. variable_curr: 139
  177. variable_cnf: 1
  178. variable_grp_uuid: 201810030225421556918962
  179. variable_cnf_flag: testrushika%2B%2Bflags%7Bendconf%7Cmoderator%7D
  180. variable_originate_retries: 3
  181. variable_enable_heartbeat_events: 10
  182. variable_origination_caller_id_name: 919033769913
  183. variable_origination_caller_id_number: 919033769913
  184. variable_originate_early_media: false
  185. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201538513887%201538513888%20IN%20IP4%2081.95.124.27%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2081.95.124.27%0D%0At%3D0%200%0D%0Am%3Daudio%2019674%20RTP/AVP%200%208%209%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
  186. variable_sip_outgoing_contact_uri: %3Csip%3Amod_sofia%4081.95.124.27%3A7651%3E
  187. variable_sip_req_uri: 919033769913%40demo.inextrix.com%3A5061
  188. variable_sofia_profile_name: default
  189. variable_recovery_profile_name: default
  190. variable_sip_local_network_addr: 81.95.124.27
  191. variable_sip_reply_host: 159.203.131.36
  192. variable_sip_reply_port: 5061
  193. variable_sip_network_ip: 159.203.131.36
  194. variable_sip_network_port: 5061
  195. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
  196. variable_sip_user_agent: Asterisk%20PBX%2013.8.0
  197. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH,%20MESSAGE
  198. variable_sip_recover_contact: %3Csip%3A919033769913%40159.203.131.36%3A5061%3E
  199. variable_sip_full_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKerZme8aH16ccj%3Breceived%3D81.95.124.27%3Brport%3D7651
  200. variable_sip_recover_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKerZme8aH16ccj%3Breceived%3D81.95.124.27%3Brport%3D7651
  201. variable_sip_from_display: 919033769913
  202. variable_sip_full_from: %22919033769913%22%20%3Csip%3A919033769913%4081.95.124.27%3E%3Btag%3DQDc5017ymmvag
  203. variable_sip_full_to: %3Csip%3A919033769913%40demo.inextrix.com%3A5061%3E%3Btag%3Das634ae868
  204. variable_sip_from_user: 919033769913
  205. variable_sip_from_uri: 919033769913%4081.95.124.27
  206. variable_sip_from_host: 81.95.124.27
  207. variable_sip_to_user: 919033769913
  208. variable_sip_to_port: 5061
  209. variable_sip_to_uri: 919033769913%40demo.inextrix.com%3A5061
  210. variable_sip_to_host: demo.inextrix.com
  211. variable_sip_contact_user: 919033769913
  212. variable_sip_contact_port: 5061
  213. variable_sip_contact_uri: 919033769913%40159.203.131.36%3A5061
  214. variable_sip_contact_host: 159.203.131.36
  215. variable_sip_to_tag: as634ae868
  216. variable_sip_from_tag: QDc5017ymmvag
  217. variable_sip_cseq: 128930716
  218. variable_sip_call_id: 82e7ac49-4156-1237-49bb-1e86ec3fb419
  219. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%201284469274%201284469274%20IN%20IP4%20159.203.131.36%0D%0As%3DAsterisk%20PBX%2013.8.0%0D%0Ac%3DIN%20IP4%20159.203.131.36%0D%0At%3D0%200%0D%0Am%3Daudio%2018402%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dmaxptime%3A150%0D%0A
  220. variable_rtp_use_codec_string: PCMU,PCMA,G722,G729
  221. variable_rtp_audio_recv_pt: 0
  222. variable_rtp_use_codec_name: PCMU
  223. variable_rtp_use_codec_rate: 8000
  224. variable_rtp_use_codec_ptime: 20
  225. variable_rtp_use_codec_channels: 1
  226. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  227. variable_read_codec: PCMU
  228. variable_original_read_codec: PCMU
  229. variable_read_rate: 8000
  230. variable_original_read_rate: 8000
  231. variable_write_codec: PCMU
  232. variable_write_rate: 8000
  233. variable_dtmf_type: rfc2833
  234. variable_local_media_ip: 81.95.124.27
  235. variable_local_media_port: 19674
  236. variable_advertised_media_ip: 81.95.124.27
  237. variable_rtp_use_timer_name: soft
  238. variable_rtp_use_pt: 0
  239. variable_rtp_use_ssrc: 1672817081
  240. variable_rtp_2833_send_payload: 101
  241. variable_rtp_2833_recv_payload: 101
  242. variable_remote_media_ip: 159.203.131.36
  243. variable_remote_media_port: 18402
  244. variable_endpoint_disposition: ANSWER
  245. variable_max_forwards: 70
  246. variable_transfer_history: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
  247. variable_transfer_source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
  248. variable_call_uuid: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  249. Hunt-Direction: outbound
  250. Hunt-Logical-Direction: outbound
  251. Hunt-Dialplan: XML
  252. Hunt-Caller-ID-Name: 919033769913
  253. Hunt-Caller-ID-Number: 919033769913
  254. Hunt-Orig-Caller-ID-Name: 919033769913
  255. Hunt-Orig-Caller-ID-Number: 919033769913
  256. Hunt-Callee-ID-Name: Outbound%20Call
  257. Hunt-Callee-ID-Number: 919033769913
  258. Hunt-Network-Addr: 159.203.131.36
  259. Hunt-ANI: 919033769913
  260. Hunt-Destination-Number: 0676338702
  261. Hunt-Unique-ID: b2d5766c-c510-4ab1-9061-59d9e5db1d69
  262. Hunt-Source: src/switch_ivr_originate.c
  263. Hunt-Transfer-Source: 1538533561%3A4e61d702-9a0a-477b-8038-b4dab8a2fbab%3Abl_xfer%3A0676338702/default/XML
  264. Hunt-Context: default
  265. Hunt-RDNIS: 919033769913
  266. Hunt-Channel-Name: sofia/default/919033769913%40demo.inextrix.com%3A5061
  267. Hunt-Profile-Index: 2
  268. Hunt-Profile-Created-Time: 1538533561641624
  269. Hunt-Channel-Created-Time: 1538533561401614
  270. Hunt-Channel-Answered-Time: 1538533561641624
  271. Hunt-Channel-Progress-Time: 0
  272. Hunt-Channel-Progress-Media-Time: 0
  273. Hunt-Channel-Hangup-Time: 0
  274. Hunt-Channel-Transfer-Time: 0
  275. Hunt-Channel-Resurrect-Time: 0
  276. Hunt-Channel-Bridged-Time: 0
  277. Hunt-Channel-Last-Hold: 0
  278. Hunt-Channel-Hold-Accum: 0
  279. Hunt-Screen-Bit: true
  280. Hunt-Privacy-Hide-Name: false
  281. Hunt-Privacy-Hide-Number: false
  282.  
  283. '
  284. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_value:
  285. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] tag_name:
  286. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_name:
  287. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] section: dialplan
  288. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Dialed number : 0676338702
  289. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : 1
  290. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call conference_flags : testrushika++flags{endconf|moderator}
  291. 2018-10-03 02:26:01.661612 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : outbound
  292. 2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [GET_CONFERNCE] Query :SELECT t1.*,t2.conference_parameters from tbl_conference as t1,tbl_conference_parameters as t2 where t1.id= 1 and t2.conference_id=t1.id limit 1
  293. 2018-10-03 02:26:01.661612 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  294. <document type="freeswitch/xml">
  295. <section name="dialplan" description="Audio Conference Dialplan">
  296. <context name="default">
  297. <extension name="0676338702">
  298. <condition field="destination_number" expression="0676338702">
  299. <action application="set" data="effective_destination_number=0676338702"/>
  300. <action application="set" data="callstart=2018-10-03 02:26:01"/>
  301. <action application="avmd" data="start"/>
  302. <action application="set" data="hangup_after_bridge=true"/>
  303. <action application="set" data="continue_on_fail=true"/>
  304. <action application="set" data="ignore_early_media=true"/>
  305. <action application="set" data="playback_terminators=none"/>
  306. <action application="answer"/>
  307. <action application="sleep" data="2000"/>
  308. <action application="playback" data="conference/conf-welcome.wav"/>
  309. <action application="sleep" data="2000"/>
  310. <action application="set" data="conference_controls=plain"/>
  311. <action application="conference" data="0676338702@testrushika++flags{endconf|moderator}"/>
  312. <action application="avmd" data="stop"/>
  313. </condition>
  314. </extension>
  315. <extension name="Announce Conf Count (Inline)">
  316. <condition field="destination_number" expression="^ANNOUNCE_CONF_COUNT_PRIVATE$">
  317. <action application="set" data="conf_count=${conference ${conference_name} list count}"/>
  318. <action application="log" data="INFO ${conf_count}"/>
  319. <action application="say" data="en number pronounced ${conf_count}"/>
  320. </condition>
  321. </extension>
  322. </context>
  323. </section>
  324. </document>
  325. 2018-10-03 02:26:01.661612 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5f40468e70 released.
  326. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 parsing [default->0676338702] continue=false
  327. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Regex (PASS) [0676338702] destination_number(0676338702) =~ /0676338702/ break=on-false
  328. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(effective_destination_number=0676338702)
  329. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(callstart=2018-10-03 02:26:01)
  330. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action avmd(start)
  331. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(hangup_after_bridge=true)
  332. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(continue_on_fail=true)
  333. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(ignore_early_media=true)
  334. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(playback_terminators=none)
  335. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action answer()
  336. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action sleep(2000)
  337. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action playback(conference/conf-welcome.wav)
  338. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action sleep(2000)
  339. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action set(conference_controls=plain)
  340. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action conference(0676338702@testrushika++flags{endconf|moderator})
  341. Dialplan: sofia/default/919033769913@demo.inextrix.com:5061 Action avmd(stop)
  342. 2018-10-03 02:26:01.661612 [INFO] switch_channel.c:3129 sofia/default/919033769913@demo.inextrix.com:5061 Flipping CID from "919033769913" <919033769913> to "Outbound Call" <919033769913>
  343. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:286 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_EXECUTE
  344. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:643 (sofia/default/919033769913@demo.inextrix.com:5061) State ROUTING going to sleep
  345. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_EXECUTE (Cur 1 Tot 16)
  346. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:650 (sofia/default/919033769913@demo.inextrix.com:5061) State EXECUTE
  347. 2018-10-03 02:26:01.661612 [DEBUG] mod_sofia.c:198 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA EXECUTE
  348. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_state_machine.c:328 sofia/default/919033769913@demo.inextrix.com:5061 Standard EXECUTE
  349. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(effective_destination_number=0676338702)
  350. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [effective_destination_number]=[0676338702]
  351. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(callstart=2018-10-03 02:26:01)
  352. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [callstart]=[2018-10-03 02:26:01]
  353. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 avmd(start)
  354. 2018-10-03 02:26:01.661612 [WARNING] mod_avmd.c:1184 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
  355. 2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:834 Avmd dynamic configuration: debug [0], report_status [1], fast_math [0], require_continuous_streak [1], sample_n_continuous_streak [15], sample_n_to_skip [15], simplified_estimation [1], inbound_channel [0], outbound_channel [1]
  356. 2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:372 Avmd session initialized, [8000] samples/s
  357. 2018-10-03 02:26:01.661612 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/919033769913@demo.inextrix.com:5061
  358. 2018-10-03 02:26:01.661612 [INFO] mod_avmd.c:1109 Avmd on channel [sofia/default/919033769913@demo.inextrix.com:5061] started!
  359. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(hangup_after_bridge=true)
  360. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [hangup_after_bridge]=[true]
  361. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(continue_on_fail=true)
  362. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [continue_on_fail]=[true]
  363. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(ignore_early_media=true)
  364. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [ignore_early_media]=[true]
  365. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(playback_terminators=none)
  366. 2018-10-03 02:26:01.661612 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [playback_terminators]=[none]
  367. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 answer()
  368. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 sleep(2000)
  369. 2018-10-03 02:26:02.181609 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  370. 2018-10-03 02:26:02.181609 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
  371. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 playback(conference/conf-welcome.wav)
  372. 2018-10-03 02:26:03.701610 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  373. 2018-10-03 02:26:04.921612 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/conference/conf-welcome.wav
  374. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 sleep(2000)
  375. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 set(conference_controls=plain)
  376. 2018-10-03 02:26:06.921614 [DEBUG] mod_dptools.c:1548 SET sofia/default/919033769913@demo.inextrix.com:5061 [conference_controls]=[plain]
  377. EXECUTE sofia/default/919033769913@demo.inextrix.com:5061 conference(0676338702@testrushika++flags{endconf|moderator})
  378. 2018-10-03 02:26:06.941609 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
  379. 2018-10-03 02:26:06.941609 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  380. 2018-10-03 02:26:06.941609 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  381. 2018-10-03 02:26:06.941609 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 160
  382. 2018-10-03 02:26:06.941609 [DEBUG] switch_core_codec.c:223 sofia/default/919033769913@demo.inextrix.com:5061 Push codec L16:100
  383. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '0' to 'mute'
  384. 2018-10-03 02:26:06.961620 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  385. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d480
  386. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '1' to 'vol talk dn'
  387. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d4b0
  388. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '2' to 'vol talk zero'
  389. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d4e0
  390. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '3' to 'vol talk up'
  391. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d510
  392. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '4' to 'vol listen dn'
  393. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d540
  394. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '5' to 'vol listen zero'
  395. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d570
  396. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '6' to 'vol listen up'
  397. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d5a0
  398. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '7' to 'energy dn'
  399. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d5d0
  400. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '8' to 'energy equ'
  401. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d600
  402. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '9' to 'energy up'
  403. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d630
  404. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '*' to 'deaf mute'
  405. 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d660
  406. 2018-10-03 02:26:06.961620 [DEBUG] conference_member.c:128 sofia/default/919033769913@demo.inextrix.com:5061 binding '#' to 'execute_application'
  407. span style="color: goldenrod; font-weight: bold;"> 2018-10-03 02:26:06.961620 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f4046d690
  408. span style="color: green; font-weight: bold;"> 2018-10-03 02:26:06.961620 [INFO] mod_conference.c:370 Auto recording file: /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-26-06.wav
  409. 2018-10-03 02:26:06.961620 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMU
  410. 2018-10-03 02:26:06.961620 [DEBUG] conference_loop.c:1292 Outbound conference channel answered, setting CFLAG_ANSWERED
  411. 2018-10-03 02:26:06.961620 [DEBUG] conference_record.c:302 Setup timer success interval: 20  samples: 160
  412. 2018-10-03 02:26:11.641612 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  413. 2018-10-03 02:26:21.661688 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  414. 2018-10-03 02:26:31.681678 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  415. freeswitch@astpp03> show calls
  416. uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
  417. b2d5766c-c510-4ab1-9061-59d9e5db1d69,outbound,2018-10-03 02:26:01,1538533561,sofia/default/919033769913@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,919033769913,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b2d5766c-c510-4ab1-9061-59d9e5db1d69,astpp03,,,,,,,,,,,,,,,,,,,,,,
  418.  
  419. 1 total.
  420.  
  421. 2018-10-03 02:26:41.701777 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  422. 2018-10-03 02:26:51.721681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  423. 2018-10-03 02:27:01.741646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  424. 2018-10-03 02:27:11.761609 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  425. 2018-10-03 02:27:21.781618 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  426. 2018-10-03 02:27:31.801681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  427. 2018-10-03 02:27:41.821681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  428. 2018-10-03 02:27:51.841604 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  429. 2018-10-03 02:28:01.861729 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  430. 2018-10-03 02:28:11.881744 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  431. 2018-10-03 02:28:21.901700 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  432. 2018-10-03 02:28:31.921678 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  433. 2018-10-03 02:28:41.941681 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  434. freeswitch@astpp03> show calls
  435. uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
  436. b2d5766c-c510-4ab1-9061-59d9e5db1d69,outbound,2018-10-03 02:26:01,1538533561,sofia/default/919033769913@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,919033769913,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b2d5766c-c510-4ab1-9061-59d9e5db1d69,astpp03,,,,,,,,,,,,,,,,,,,,,,
  437.  
  438. 1 total.
  439.  
  440. 2018-10-03 02:28:51.961678 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  441. 2018-10-03 02:29:01.981646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  442. 2018-10-03 02:29:12.001672 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  443. 2018-10-03 02:29:22.021682 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  444. 2018-10-03 02:29:32.041672 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  445. 2018-10-03 02:29:42.061654 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  446. 2018-10-03 02:29:52.081642 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  447. 2018-10-03 02:30:02.101613 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  448. 2018-10-03 02:30:03.361616 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  449. 2018-10-03 02:30:03.361616 [NOTICE] switch_channel.c:1104 New Channel sofia/default/447375661939@demo.inextrix.com:5061 [b85448fa-575b-4b84-97a4-52d594b6b823]
  450. 2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:4819 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_NEW -> CS_INIT
  451. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_INIT (Cur 2 Tot 17)
  452. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:627 (sofia/default/447375661939@demo.inextrix.com:5061) State INIT
  453. 2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:90 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA INIT
  454. 2018-10-03 02:30:03.361616 [DEBUG] sofia_glue.c:1295 sofia/default/447375661939@demo.inextrix.com:5061 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  455. Local SDP:
  456. v=0
  457. o=FreeSWITCH 1538508525 1538508526 IN IP4 81.95.124.27
  458. s=FreeSWITCH
  459. c=IN IP4 81.95.124.27
  460. t=0 0
  461. m=audio 25278 RTP/AVP 0 8 9 18 101
  462. a=rtpmap:0 PCMU/8000
  463. a=rtpmap:8 PCMA/8000
  464. a=rtpmap:9 G722/8000
  465. a=rtpmap:18 G729/8000
  466. a=rtpmap:101 telephone-event/8000
  467. a=fmtp:101 0-16
  468. a=ptime:20
  469. a=sendrecv
  470.  
  471. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:40 sofia/default/447375661939@demo.inextrix.com:5061 Standard INIT
  472. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:48 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_INIT -> CS_ROUTING
  473. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:627 (sofia/default/447375661939@demo.inextrix.com:5061) State INIT going to sleep
  474. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 2 Tot 17)
  475. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING
  476. 2018-10-03 02:30:03.361616 [DEBUG] mod_sofia.c:143 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA ROUTING
  477. 2018-10-03 02:30:03.361616 [DEBUG] switch_ivr_originate.c:67 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  478. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING going to sleep
  479. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 17)
  480. 2018-10-03 02:30:03.361616 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [calling][0]
  481. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:662 (sofia/default/447375661939@demo.inextrix.com:5061) State CONSUME_MEDIA
  482. 2018-10-03 02:30:03.361616 [DEBUG] switch_core_state_machine.c:662 (sofia/default/447375661939@demo.inextrix.com:5061) State CONSUME_MEDIA going to sleep
  483. 2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [completing][200]
  484. 2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7094 Remote SDP:
  485. v=0
  486. o=root 776637440 776637440 IN IP4 159.203.131.36
  487. s=Asterisk PBX 13.8.0
  488. c=IN IP4 159.203.131.36
  489. t=0 0
  490. m=audio 14966 RTP/AVP 0 8 101
  491. a=rtpmap:0 PCMU/8000
  492. a=rtpmap:8 PCMA/8000
  493. a=rtpmap:101 telephone-event/8000
  494. a=fmtp:101 0-16
  495. a=maxptime:150
  496.  
  497. 2018-10-03 02:30:03.441605 [DEBUG] sofia.c:7084 Channel sofia/default/447375661939@demo.inextrix.com:5061 entering state [ready][200]
  498. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  499. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  500. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  501. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G722:9:8000:20:64000:1]
  502. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  503. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMU:0:8000:20:64000:1]
  504. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
  505. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  506. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G722:9:8000:20:64000:1]
  507. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[G729:18:8000:20:8000:1]
  508. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  509. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/447375661939@demo.inextrix.com:5061 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  510. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_codec.c:111 sofia/default/447375661939@demo.inextrix.com:5061 Original read codec set to PCMU:0
  511. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  512. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:4767 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101 recv payload to 101
  513. 2018-10-03 02:30:03.441605 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/447375661939@demo.inextrix.com:5061] 81.95.124.27 port 25278 -> 159.203.131.36 port 14966 codec: 0 ms: 20
  514. 2018-10-03 02:30:03.441605 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  515. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7179 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf send payload to 101
  516. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7186 sofia/default/447375661939@demo.inextrix.com:5061 Set 2833 dtmf receive payload to 101
  517. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_media.c:7209 sofia/default/447375661939@demo.inextrix.com:5061 Set rtp dtmf delay to 40
  518. 2018-10-03 02:30:03.461613 [INFO] switch_core_session.c:1619 sofia/default/447375661939@demo.inextrix.com:5061 setting session heartbeat to 10 second(s).
  519. 2018-10-03 02:30:03.461613 [NOTICE] sofia.c:8218 Channel [sofia/default/447375661939@demo.inextrix.com:5061] has been answered
  520. 2018-10-03 02:30:03.461613 [DEBUG] switch_channel.c:3773 (sofia/default/447375661939@demo.inextrix.com:5061) Callstate Change DOWN -> ACTIVE
  521. 2018-10-03 02:30:03.461613 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/447375661939@demo.inextrix.com:5061]
  522. 2018-10-03 02:30:03.461613 [DEBUG] switch_ivr.c:2165 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_CONSUME_MEDIA -> CS_ROUTING
  523. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_ROUTING (Cur 2 Tot 17)
  524. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING
  525. 2018-10-03 02:30:03.461613 [DEBUG] mod_sofia.c:143 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA ROUTING
  526. 2018-10-03 02:30:03.461613 [DEBUG] switch_core_state_machine.c:236 sofia/default/447375661939@demo.inextrix.com:5061 Standard ROUTING
  527. 2018-10-03 02:30:03.461613 [INFO] mod_dialplan_xml.c:637 Processing 919033769913 <919033769913>->0676338702 in context default
  528. 2018-10-03 02:30:03.461613 [NOTICE] switch_ivr.c:2172 Transfer sofia/default/447375661939@demo.inextrix.com:5061 to XML[0676338702@default]
  529. 2018-10-03 02:30:03.461613 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f5f20073b10 Connected.
  530. 2018-10-03 02:30:03.461613 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [LOAD_CONF] Query :SELECT name,value FROM  tbl_settings WHERE group_title IN ('global')
  531. 2018-10-03 02:30:03.461613 [NOTICE] switch_cpp.cpp:1365 [Audio Conference] [xml_handler] Params:
  532. 'Event-Name: REQUEST_PARAMS
  533. Core-UUID: f4183a48-fe2b-4698-83d4-87d80b08381d
  534. FreeSWITCH-Hostname: astpp03
  535. FreeSWITCH-Switchname: astpp03
  536. FreeSWITCH-IPv4: 81.95.124.27
  537. FreeSWITCH-IPv6: %3A%3A1
  538. Event-Date-Local: 2018-10-03%2002%3A30%3A03
  539. Event-Date-GMT: Wed,%2003%20Oct%202018%2002%3A30%3A03%20GMT
  540. Event-Date-Timestamp: 1538533803461613
  541. Event-Calling-File: mod_dialplan_xml.c
  542. Event-Calling-Function: dialplan_xml_locate
  543. Event-Calling-Line-Number: 608
  544. Event-Sequence: 29864
  545. Channel-State: CS_ROUTING
  546. Channel-Call-State: ACTIVE
  547. Channel-State-Number: 2
  548. Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
  549. Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
  550. Call-Direction: outbound
  551. Presence-Call-Direction: outbound
  552. Channel-HIT-Dialplan: false
  553. Channel-Call-UUID: b85448fa-575b-4b84-97a4-52d594b6b823
  554. Answer-State: answered
  555. Channel-Read-Codec-Name: PCMU
  556. Channel-Read-Codec-Rate: 8000
  557. Channel-Read-Codec-Bit-Rate: 64000
  558. Channel-Write-Codec-Name: PCMU
  559. Channel-Write-Codec-Rate: 8000
  560. Channel-Write-Codec-Bit-Rate: 64000
  561. Caller-Direction: outbound
  562. Caller-Logical-Direction: outbound
  563. Caller-Dialplan: XML
  564. Caller-Caller-ID-Name: 919033769913
  565. Caller-Caller-ID-Number: 919033769913
  566. Caller-Orig-Caller-ID-Name: 919033769913
  567. Caller-Orig-Caller-ID-Number: 919033769913
  568. Caller-Callee-ID-Name: Outbound%20Call
  569. Caller-Callee-ID-Number: 447375661939
  570. Caller-Network-Addr: 159.203.131.36
  571. Caller-ANI: 919033769913
  572. Caller-Destination-Number: 0676338702
  573. Caller-Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
  574. Caller-Source: src/switch_ivr_originate.c
  575. Caller-Context: default
  576. Caller-RDNIS: 447375661939
  577. Caller-Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
  578. Caller-Profile-Index: 2
  579. Caller-Profile-Created-Time: 1538533803461613
  580. Caller-Channel-Created-Time: 1538533803361616
  581. Caller-Channel-Answered-Time: 1538533803461613
  582. Caller-Channel-Progress-Time: 0
  583. Caller-Channel-Progress-Media-Time: 0
  584. Caller-Channel-Hangup-Time: 0
  585. Caller-Channel-Transfer-Time: 0
  586. Caller-Channel-Resurrect-Time: 0
  587. Caller-Channel-Bridged-Time: 0
  588. Caller-Channel-Last-Hold: 0
  589. Caller-Channel-Hold-Accum: 0
  590. Caller-Screen-Bit: true
  591. Caller-Privacy-Hide-Name: false
  592. Caller-Privacy-Hide-Number: false
  593. variable_direction: outbound
  594. variable_is_outbound: true
  595. variable_uuid: b85448fa-575b-4b84-97a4-52d594b6b823
  596. variable_session_id: 17
  597. variable_sip_profile_name: default
  598. variable_video_media_flow: sendrecv
  599. variable_audio_media_flow: sendrecv
  600. variable_channel_name: sofia/default/447375661939%40demo.inextrix.com%3A5061
  601. variable_sip_destination_url: sip%3A447375661939%40demo.inextrix.com%3A5061
  602. variable_hangup_after_bridge: true
  603. variable_ignore_early_media: true
  604. variable_conference_enforce_security: true
  605. variable_conference_enter_sound: conference/conf-entry_sound.wav
  606. variable_conference_exit_sound: conference/conf-exit_sound.wav
  607. variable_ann_name: 0
  608. variable_trunk_id: 3
  609. variable_plan_id: 1
  610. variable_prefix: 4
  611. variable_COST: 0.00000
  612. variable_c_cost: 0.00000
  613. variable_n_init_inc: 60
  614. variable_type: contact
  615. variable_contact_id: 60
  616. variable_customer_id: 1
  617. variable_n_inc: 60
  618. variable_n_rate: 0.00000
  619. variable_n_acc: 1
  620. variable_curr: 139
  621. variable_cnf: 1
  622. variable_grp_uuid: 201810030225421556918962
  623. variable_cnf_flag: testrushika%2B
  624. variable_originate_retries: 3
  625. variable_enable_heartbeat_events: 10
  626. variable_origination_caller_id_name: 919033769913
  627. variable_origination_caller_id_number: 919033769913
  628. variable_originate_early_media: false
  629. variable_rtp_local_sdp_str: v%3D0%0D%0Ao%3DFreeSWITCH%201538508525%201538508526%20IN%20IP4%2081.95.124.27%0D%0As%3DFreeSWITCH%0D%0Ac%3DIN%20IP4%2081.95.124.27%0D%0At%3D0%200%0D%0Am%3Daudio%2025278%20RTP/AVP%200%208%209%2018%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A9%20G722/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dptime%3A20%0D%0Aa%3Dsendrecv%0D%0A
  630. variable_sip_outgoing_contact_uri: %3Csip%3Amod_sofia%4081.95.124.27%3A7651%3E
  631. variable_sip_req_uri: 447375661939%40demo.inextrix.com%3A5061
  632. variable_sofia_profile_name: default
  633. variable_recovery_profile_name: default
  634. variable_sip_local_network_addr: 81.95.124.27
  635. variable_sip_reply_host: 159.203.131.36
  636. variable_sip_reply_port: 5061
  637. variable_sip_network_ip: 159.203.131.36
  638. variable_sip_network_port: 5061
  639. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b
  640. variable_sip_user_agent: Asterisk%20PBX%2013.8.0
  641. variable_sip_allow: INVITE,%20ACK,%20CANCEL,%20OPTIONS,%20BYE,%20REFER,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20PUBLISH,%20MESSAGE
  642. variable_sip_recover_contact: %3Csip%3A447375661939%40159.203.131.36%3A5061%3E
  643. variable_sip_full_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKgaj6HycrUrSHS%3Breceived%3D81.95.124.27%3Brport%3D7651
  644. variable_sip_recover_via: SIP/2.0/UDP%2081.95.124.27%3A7651%3Bbranch%3Dz9hG4bKgaj6HycrUrSHS%3Breceived%3D81.95.124.27%3Brport%3D7651
  645. variable_sip_from_display: 919033769913
  646. variable_sip_full_from: %22919033769913%22%20%3Csip%3A919033769913%4081.95.124.27%3E%3Btag%3Drp5X2vr2HXjXB
  647. variable_sip_full_to: %3Csip%3A447375661939%40demo.inextrix.com%3A5061%3E%3Btag%3Das50869c30
  648. variable_sip_from_user: 919033769913
  649. variable_sip_from_uri: 919033769913%4081.95.124.27
  650. variable_sip_from_host: 81.95.124.27
  651. variable_sip_to_user: 447375661939
  652. variable_sip_to_port: 5061
  653. variable_sip_to_uri: 447375661939%40demo.inextrix.com%3A5061
  654. variable_sip_to_host: demo.inextrix.com
  655. variable_sip_contact_user: 447375661939
  656. variable_sip_contact_port: 5061
  657. variable_sip_contact_uri: 447375661939%40159.203.131.36%3A5061
  658. variable_sip_contact_host: 159.203.131.36
  659. variable_sip_to_tag: as50869c30
  660. variable_sip_from_tag: rp5X2vr2HXjXB
  661. variable_sip_cseq: 128930837
  662. variable_sip_call_id: 131eec02-4157-1237-49bb-1e86ec3fb419
  663. variable_switch_r_sdp: v%3D0%0D%0Ao%3Droot%20776637440%20776637440%20IN%20IP4%20159.203.131.36%0D%0As%3DAsterisk%20PBX%2013.8.0%0D%0Ac%3DIN%20IP4%20159.203.131.36%0D%0At%3D0%200%0D%0Am%3Daudio%2014966%20RTP/AVP%200%208%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16%0D%0Aa%3Dmaxptime%3A150%0D%0A
  664. variable_rtp_use_codec_string: PCMU,PCMA,G722,G729
  665. variable_rtp_audio_recv_pt: 0
  666. variable_rtp_use_codec_name: PCMU
  667. variable_rtp_use_codec_rate: 8000
  668. variable_rtp_use_codec_ptime: 20
  669. variable_rtp_use_codec_channels: 1
  670. variable_rtp_last_audio_codec_string: PCMU%408000h%4020i%401c
  671. variable_read_codec: PCMU
  672. variable_original_read_codec: PCMU
  673. variable_read_rate: 8000
  674. variable_original_read_rate: 8000
  675. variable_write_codec: PCMU
  676. variable_write_rate: 8000
  677. variable_dtmf_type: rfc2833
  678. variable_local_media_ip: 81.95.124.27
  679. variable_local_media_port: 25278
  680. variable_advertised_media_ip: 81.95.124.27
  681. variable_rtp_use_timer_name: soft
  682. variable_rtp_use_pt: 0
  683. variable_rtp_use_ssrc: 2142584731
  684. variable_rtp_2833_send_payload: 101
  685. variable_rtp_2833_recv_payload: 101
  686. variable_remote_media_ip: 159.203.131.36
  687. variable_remote_media_port: 14966
  688. variable_endpoint_disposition: ANSWER
  689. variable_max_forwards: 70
  690. variable_call_uuid: b85448fa-575b-4b84-97a4-52d594b6b823
  691. Hunt-Direction: outbound
  692. Hunt-Logical-Direction: outbound
  693. Hunt-Dialplan: XML
  694. Hunt-Caller-ID-Name: 919033769913
  695. Hunt-Caller-ID-Number: 919033769913
  696. Hunt-Orig-Caller-ID-Name: 919033769913
  697. Hunt-Orig-Caller-ID-Number: 919033769913
  698. Hunt-Callee-ID-Name: Outbound%20Call
  699. Hunt-Callee-ID-Number: 447375661939
  700. Hunt-Network-Addr: 159.203.131.36
  701. Hunt-ANI: 919033769913
  702. Hunt-Destination-Number: 0676338702
  703. Hunt-Unique-ID: b85448fa-575b-4b84-97a4-52d594b6b823
  704. Hunt-Source: src/switch_ivr_originate.c
  705. Hunt-Context: default
  706. Hunt-RDNIS: 447375661939
  707. Hunt-Channel-Name: sofia/default/447375661939%40demo.inextrix.com%3A5061
  708. Hunt-Profile-Index: 2
  709. Hunt-Profile-Created-Time: 1538533803461613
  710. Hunt-Channel-Created-Time: 1538533803361616
  711. Hunt-Channel-Answered-Time: 1538533803461613
  712. Hunt-Channel-Progress-Time: 0
  713. Hunt-Channel-Progress-Media-Time: 0
  714. Hunt-Channel-Hangup-Time: 0
  715. Hunt-Channel-Transfer-Time: 0
  716. Hunt-Channel-Resurrect-Time: 0
  717. Hunt-Channel-Bridged-Time: 0
  718. Hunt-Channel-Last-Hold: 0
  719. Hunt-Channel-Hold-Accum: 0
  720. Hunt-Screen-Bit: true
  721. Hunt-Privacy-Hide-Name: false
  722. Hunt-Privacy-Hide-Number: false
  723.  
  724. '
  725. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_value:
  726. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] key_name:
  727. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] tag_name:
  728. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [xml_REQUEST] section: dialplan
  729. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Dialed number : 0676338702
  730. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : 1
  731. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call conference_flags : testrushika+
  732. 2018-10-03 02:30:03.461613 [INFO] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Call direction : outbound
  733. 2018-10-03 02:30:03.461613 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [GET_CONFERNCE] Query :SELECT t1.*,t2.conference_parameters from tbl_conference as t1,tbl_conference_parameters as t2 where t1.id= 1 and t2.conference_id=t1.id limit 1
  734. 2018-10-03 02:30:03.481610 [DEBUG] switch_cpp.cpp:1365 [Audio Conference] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
  735. <document type="freeswitch/xml">
  736. <section name="dialplan" description="Audio Conference Dialplan">
  737. <context name="default">
  738. <extension name="0676338702">
  739. <condition field="destination_number" expression="0676338702">
  740. <action application="set" data="effective_destination_number=0676338702"/>
  741. <action application="set" data="callstart=2018-10-03 02:30:03"/>
  742. <action application="avmd" data="start"/>
  743. <action application="set" data="hangup_after_bridge=true"/>
  744. <action application="set" data="continue_on_fail=true"/>
  745. <action application="set" data="ignore_early_media=true"/>
  746. <action application="set" data="playback_terminators=none"/>
  747. <action application="answer"/>
  748. <action application="sleep" data="2000"/>
  749. <action application="playback" data="conference/conf-welcome.wav"/>
  750. <action application="sleep" data="2000"/>
  751. <action application="set" data="conference_controls=plain"/>
  752. <action application="conference" data="0676338702@testrushika+"/>
  753. <action application="avmd" data="stop"/>
  754. </condition>
  755. </extension>
  756. <extension name="Announce Conf Count (Inline)">
  757. <condition field="destination_number" expression="^ANNOUNCE_CONF_COUNT_PRIVATE$">
  758. <action application="set" data="conf_count=${conference ${conference_name} list count}"/>
  759. <action application="log" data="INFO ${conf_count}"/>
  760. <action application="say" data="en number pronounced ${conf_count}"/>
  761. </condition>
  762. </extension>
  763. </context>
  764. </section>
  765. </document>
  766. 2018-10-03 02:30:03.481610 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f5f20073b10 released.
  767. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 parsing [default->0676338702] continue=false
  768. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Regex (PASS) [0676338702] destination_number(0676338702) =~ /0676338702/ break=on-false
  769. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(effective_destination_number=0676338702)
  770. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(callstart=2018-10-03 02:30:03)
  771. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action avmd(start)
  772. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(hangup_after_bridge=true)
  773. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(continue_on_fail=true)
  774. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(ignore_early_media=true)
  775. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(playback_terminators=none)
  776. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action answer()
  777. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action sleep(2000)
  778. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action playback(conference/conf-welcome.wav)
  779. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action sleep(2000)
  780. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action set(conference_controls=plain)
  781. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action conference(0676338702@testrushika+)
  782. Dialplan: sofia/default/447375661939@demo.inextrix.com:5061 Action avmd(stop)
  783. 2018-10-03 02:30:03.481610 [INFO] switch_channel.c:3129 sofia/default/447375661939@demo.inextrix.com:5061 Flipping CID from "919033769913" <919033769913> to "Outbound Call" <447375661939>
  784. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:286 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_ROUTING -> CS_EXECUTE
  785. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:643 (sofia/default/447375661939@demo.inextrix.com:5061) State ROUTING going to sleep
  786. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_EXECUTE (Cur 2 Tot 17)
  787. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:650 (sofia/default/447375661939@demo.inextrix.com:5061) State EXECUTE
  788. 2018-10-03 02:30:03.481610 [DEBUG] mod_sofia.c:198 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA EXECUTE
  789. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_state_machine.c:328 sofia/default/447375661939@demo.inextrix.com:5061 Standard EXECUTE
  790. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(effective_destination_number=0676338702)
  791. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [effective_destination_number]=[0676338702]
  792. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(callstart=2018-10-03 02:30:03)
  793. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [callstart]=[2018-10-03 02:30:03]
  794. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 avmd(start)
  795. 2018-10-03 02:30:03.481610 [WARNING] mod_avmd.c:1184 YOU ARE USING DEPRECATED APP INTERFACE. Please read documentation about new syntax
  796. 2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:834 Avmd dynamic configuration: debug [0], report_status [1], fast_math [0], require_continuous_streak [1], sample_n_continuous_streak [15], sample_n_to_skip [15], simplified_estimation [1], inbound_channel [0], outbound_channel [1]
  797. 2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:372 Avmd session initialized, [8000] samples/s
  798. 2018-10-03 02:30:03.481610 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/default/447375661939@demo.inextrix.com:5061
  799. 2018-10-03 02:30:03.481610 [INFO] mod_avmd.c:1109 Avmd on channel [sofia/default/447375661939@demo.inextrix.com:5061] started!
  800. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(hangup_after_bridge=true)
  801. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [hangup_after_bridge]=[true]
  802. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(continue_on_fail=true)
  803. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [continue_on_fail]=[true]
  804. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(ignore_early_media=true)
  805. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [ignore_early_media]=[true]
  806. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(playback_terminators=none)
  807. 2018-10-03 02:30:03.481610 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [playback_terminators]=[none]
  808. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 answer()
  809. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 sleep(2000)
  810. 2018-10-03 02:30:03.981612 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  811. 2018-10-03 02:30:03.981612 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMU:0
  812. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 playback(conference/conf-welcome.wav)
  813. 2018-10-03 02:30:05.481614 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  814. 2018-10-03 02:30:06.421614 [NOTICE] sofia.c:1012 Hangup sofia/default/919033769913@demo.inextrix.com:5061 [CS_EXECUTE] [NORMAL_CLEARING]
  815. 2018-10-03 02:30:06.421614 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING
  816. 2018-10-03 02:30:06.421614 [DEBUG] mod_conference.c:2247 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [UNBRIDGE] (channel is hungup already)
  817. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_media.c:9779 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [HARD_MUTE] (channel is hungup already)
  818. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_codec.c:248 sofia/default/919033769913@demo.inextrix.com:5061 Restore previous codec PCMU:0.
  819. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_session.c:2815 sofia/default/919033769913@demo.inextrix.com:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  820. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:650 (sofia/default/919033769913@demo.inextrix.com:5061) State EXECUTE going to sleep
  821. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_HANGUP (Cur 2 Tot 17)
  822. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/919033769913@demo.inextrix.com:5061
  823. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:850 (sofia/default/919033769913@demo.inextrix.com:5061) Callstate Change ACTIVE -> HANGUP
  824. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:852 (sofia/default/919033769913@demo.inextrix.com:5061) State HANGUP
  825. 2018-10-03 02:30:06.421614 [DEBUG] mod_sofia.c:438 Channel sofia/default/919033769913@demo.inextrix.com:5061 hanging up, cause: NORMAL_CLEARING
  826. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:60 sofia/default/919033769913@demo.inextrix.com:5061 Standard HANGUP, cause: NORMAL_CLEARING
  827. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:852 (sofia/default/919033769913@demo.inextrix.com:5061) State HANGUP going to sleep
  828. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:619 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_HANGUP -> CS_REPORTING
  829. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_REPORTING (Cur 2 Tot 17)
  830. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:938 (sofia/default/919033769913@demo.inextrix.com:5061) State REPORTING
  831. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:174 sofia/default/919033769913@demo.inextrix.com:5061 Standard REPORTING, cause: NORMAL_CLEARING
  832. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:938 (sofia/default/919033769913@demo.inextrix.com:5061) State REPORTING going to sleep
  833. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:610 (sofia/default/919033769913@demo.inextrix.com:5061) State Change CS_REPORTING -> CS_DESTROY
  834. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_session.c:1665 Session 16 (sofia/default/919033769913@demo.inextrix.com:5061) Locked, Waiting on external entities
  835. 2018-10-03 02:30:06.421614 [NOTICE] switch_core_session.c:1683 Session 16 (sofia/default/919033769913@demo.inextrix.com:5061) Ended
  836. 2018-10-03 02:30:06.421614 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/919033769913@demo.inextrix.com:5061 [CS_DESTROY]
  837. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:741 (sofia/default/919033769913@demo.inextrix.com:5061) Running State Change CS_DESTROY (Cur 1 Tot 17)
  838. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:751 (sofia/default/919033769913@demo.inextrix.com:5061) State DESTROY
  839. 2018-10-03 02:30:06.421614 [DEBUG] mod_sofia.c:343 sofia/default/919033769913@demo.inextrix.com:5061 SOFIA DESTROY
  840. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:181 sofia/default/919033769913@demo.inextrix.com:5061 Standard DESTROY
  841. 2018-10-03 02:30:06.421614 [DEBUG] switch_core_state_machine.c:751 (sofia/default/919033769913@demo.inextrix.com:5061) State DESTROY going to sleep
  842. 2018-10-03 02:30:06.441612 [DEBUG] mod_conference.c:788 Write Lock ON
  843. 2018-10-03 02:30:06.441612 [INFO] conference_record.c:417 Recording of /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-26-06.wav Stopped
  844. 2018-10-03 02:30:06.441612 [DEBUG] mod_conference.c:791 Write Lock OFF
  845. 2018-10-03 02:30:06.721609 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/conference/conf-welcome.wav
  846. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 sleep(2000)
  847. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 set(conference_controls=plain)
  848. 2018-10-03 02:30:08.741615 [DEBUG] mod_dptools.c:1548 SET sofia/default/447375661939@demo.inextrix.com:5061 [conference_controls]=[plain]
  849. EXECUTE sofia/default/447375661939@demo.inextrix.com:5061 conference(0676338702@testrushika+)
  850. 2018-10-03 02:30:08.761608 [DEBUG] mod_conference.c:3076 using channel sound prefix: /usr/local/freeswitch/sounds/en/us/callie
  851. 2018-10-03 02:30:08.761608 [DEBUG] conference_member.c:1691 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  852. 2018-10-03 02:30:08.761608 [DEBUG] conference_member.c:1738 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  853. 2018-10-03 02:30:08.761608 [DEBUG] mod_conference.c:220 Setup timer success interval: 20  samples: 160
  854. 2018-10-03 02:30:08.761608 [DEBUG] switch_core_codec.c:223 sofia/default/447375661939@demo.inextrix.com:5061 Push codec L16:100
  855. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '0' to 'mute'
  856. 2018-10-03 02:30:08.781610 [INFO] switch_ivr_async.c:214 Digit parser mod_conference: Setting realm to 'conf'
  857. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 0/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20077fd0
  858. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '1' to 'vol talk dn'
  859. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 1/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078000
  860. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '2' to 'vol talk zero'
  861. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 2/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078030
  862. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '3' to 'vol talk up'
  863. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 3/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078060
  864. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '4' to 'vol listen dn'
  865. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 4/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078090
  866. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '5' to 'vol listen zero'
  867. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 5/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200780c0
  868. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '6' to 'vol listen up'
  869. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 6/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200780f0
  870. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '7' to 'energy dn'
  871. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 7/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078120
  872. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '8' to 'energy equ'
  873. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 8/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078150
  874. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '9' to 'energy up'
  875. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding 9/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f20078180
  876. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '*' to 'deaf mute'
  877. 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding */conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200781b0
  878. 2018-10-03 02:30:08.781610 [DEBUG] conference_member.c:128 sofia/default/447375661939@demo.inextrix.com:5061 binding '#' to 'execute_application'
  879. span style="color: goldenrod; font-weight: bold;"> 2018-10-03 02:30:08.781610 [DEBUG] switch_ivr_async.c:323 Digit parser mod_conference: binding #/conf/0 callback: 0x7f5f9d3db2a0 data: 0x7f5f200781e0
  880. span style="color: green; font-weight: bold;"> 2018-10-03 02:30:08.781610 [INFO] mod_conference.c:370 Auto recording file: /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-30-08.wav
  881. 2018-10-03 02:30:08.781610 [DEBUG] conference_loop.c:1140 Setup timer soft success interval: 20  samples: 160 from codec PCMU
  882. 2018-10-03 02:30:08.781610 [DEBUG] conference_loop.c:1292 Outbound conference channel answered, setting CFLAG_ANSWERED
  883. 2018-10-03 02:30:08.781610 [DEBUG] conference_record.c:302 Setup timer success interval: 20  samples: 160
  884. 2018-10-03 02:30:13.441671 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  885. 2018-10-03 02:30:23.461635 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  886. 2018-10-03 02:30:33.481719 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  887. 2018-10-03 02:30:43.501607 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  888. 2018-10-03 02:30:53.521767 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  889. 2018-10-03 02:31:03.541665 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  890. 2018-10-03 02:31:13.561615 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  891. 2018-10-03 02:31:23.581774 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  892. 2018-10-03 02:31:33.601646 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  893. 2018-10-03 02:31:43.621603 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  894. 2018-10-03 02:31:53.641609 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  895. 2018-10-03 02:32:03.661714 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  896. 2018-10-03 02:32:13.681673 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  897. 2018-10-03 02:32:23.701653 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  898. 2018-10-03 02:32:33.721606 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  899. 2018-10-03 02:32:43.741712 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  900. 2018-10-03 02:32:53.761642 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  901. 2018-10-03 02:33:03.781608 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  902. 2018-10-03 02:33:13.801645 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  903. 2018-10-03 02:33:23.821613 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  904. 2018-10-03 02:33:33.841619 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  905. freeswitch@astpp03>
  906. 2018-10-03 02:33:43.861648 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  907. freeswitch@astpp03> show calls
  908. uuid,direction,created,created_epoch,name,state,cid_name,cid_num,ip_addr,dest,presence_id,presence_data,accountcode,callstate,callee_name,callee_num,callee_direction,call_uuid,hostname,sent_callee_name,sent_callee_num,b_uuid,b_direction,b_created,b_created_epoch,b_name,b_state,b_cid_name,b_cid_num,b_ip_addr,b_dest,b_presence_id,b_presence_data,b_accountcode,b_callstate,b_callee_name,b_callee_num,b_callee_direction,b_sent_callee_name,b_sent_callee_num,call_created_epoch
  909. b85448fa-575b-4b84-97a4-52d594b6b823,outbound,2018-10-03 02:30:03,1538533803,sofia/default/447375661939@demo.inextrix.com:5061,CS_EXECUTE,Outbound Call,447375661939,159.203.131.36,0676338702,,,,ACTIVE,919033769913,919033769913,RECV,b85448fa-575b-4b84-97a4-52d594b6b823,astpp03,,,,,,,,,,,,,,,,,,,,,,
  910.  
  911. 1 total.
  912.  
  913. 2018-10-03 02:33:53.881724 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  914. 2018-10-03 02:34:03.901611 [DEBUG] mod_nibblebill.c:695 Received request via SESSION_HEARTBEAT!
  915. 2018-10-03 02:34:08.221620 [DEBUG] switch_core_io.c:780 Engaging Read Buffer at 320 bytes vs 89
  916. 2018-10-03 02:34:08.221620 [NOTICE] sofia.c:1012 Hangup sofia/default/447375661939@demo.inextrix.com:5061 [CS_EXECUTE] [NORMAL_CLEARING]
  917. 2018-10-03 02:34:08.221620 [INFO] conference_loop.c:1469 Channel leaving conference, cause: NORMAL_CLEARING
  918. 2018-10-03 02:34:08.221620 [DEBUG] mod_conference.c:2247 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [UNBRIDGE] (channel is hungup already)
  919. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_media.c:9779 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [HARD_MUTE] (channel is hungup already)
  920. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_codec.c:248 sofia/default/447375661939@demo.inextrix.com:5061 Restore previous codec PCMU:0.
  921. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_session.c:2815 sofia/default/447375661939@demo.inextrix.com:5061 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  922. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:650 (sofia/default/447375661939@demo.inextrix.com:5061) State EXECUTE going to sleep
  923. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_HANGUP (Cur 1 Tot 17)
  924. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_media_bug.c:1298 Removing BUG from sofia/default/447375661939@demo.inextrix.com:5061
  925. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:850 (sofia/default/447375661939@demo.inextrix.com:5061) Callstate Change ACTIVE -> HANGUP
  926. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:852 (sofia/default/447375661939@demo.inextrix.com:5061) State HANGUP
  927. 2018-10-03 02:34:08.241620 [DEBUG] mod_sofia.c:438 Channel sofia/default/447375661939@demo.inextrix.com:5061 hanging up, cause: NORMAL_CLEARING
  928. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:60 sofia/default/447375661939@demo.inextrix.com:5061 Standard HANGUP, cause: NORMAL_CLEARING
  929. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:852 (sofia/default/447375661939@demo.inextrix.com:5061) State HANGUP going to sleep
  930. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:619 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_HANGUP -> CS_REPORTING
  931. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:584 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_REPORTING (Cur 1 Tot 17)
  932. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:938 (sofia/default/447375661939@demo.inextrix.com:5061) State REPORTING
  933. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:174 sofia/default/447375661939@demo.inextrix.com:5061 Standard REPORTING, cause: NORMAL_CLEARING
  934. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:938 (sofia/default/447375661939@demo.inextrix.com:5061) State REPORTING going to sleep
  935. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:610 (sofia/default/447375661939@demo.inextrix.com:5061) State Change CS_REPORTING -> CS_DESTROY
  936. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_session.c:1665 Session 17 (sofia/default/447375661939@demo.inextrix.com:5061) Locked, Waiting on external entities
  937. 2018-10-03 02:34:08.241620 [NOTICE] switch_core_session.c:1683 Session 17 (sofia/default/447375661939@demo.inextrix.com:5061) Ended
  938. 2018-10-03 02:34:08.241620 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/447375661939@demo.inextrix.com:5061 [CS_DESTROY]
  939. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:741 (sofia/default/447375661939@demo.inextrix.com:5061) Running State Change CS_DESTROY (Cur 0 Tot 17)
  940. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/447375661939@demo.inextrix.com:5061) State DESTROY
  941. 2018-10-03 02:34:08.241620 [DEBUG] mod_sofia.c:343 sofia/default/447375661939@demo.inextrix.com:5061 SOFIA DESTROY
  942. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:181 sofia/default/447375661939@demo.inextrix.com:5061 Standard DESTROY
  943. 2018-10-03 02:34:08.241620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/447375661939@demo.inextrix.com:5061) State DESTROY going to sleep
  944. 2018-10-03 02:34:08.241620 [DEBUG] mod_conference.c:788 Write Lock ON
  945. 2018-10-03 02:34:08.241620 [INFO] conference_record.c:417 Recording of /usr/local/freeswitch/recordings/testrushika_2018-10-03-02-30-08.wav Stopped
  946. 2018-10-03 02:34:08.241620 [DEBUG] mod_conference.c:791 Write Lock OFF