FreeSWITCH Pastebin

Untitled

From faxlogs, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/a27e3672
Embed
Download Paste or View Raw
  1. span style="color: cornflowerblue; font-weight: bold;"> 2018-07-27 17:23:39.433640 [NOTICE] switch_channel.c:1104 New Channel sofia/default/7120041412@phone.c2a.com.au [f5ec8f6c-1155-481b-8cfe-1d29dc6631ee]
  2. 2018-07-27 17:23:39.433640 [DEBUG] switch_core_state_machine.c:584 (sofia/default/7120041412@phone.c2a.com.au) Running State Change CS_NEW (Cur 9 Tot 970429)
  3. 2018-07-27 17:23:39.433640 [DEBUG] sofia.c:9873 sofia/default/7120041412@phone.c2a.com.au receiving invite from 103.239.77.180:1491 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  4. 2018-07-27 17:23:39.433640 [DEBUG] sofia.c:10044 IP 103.239.77.180 Rejected by acl "default". Falling back to Digest auth.
  5. 2018-07-27 17:23:39.433640 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'default' for [1300368909@phone.c2a.com.au] from ip 103.239.77.180
  6. 2018-07-27 17:23:39.433640 [DEBUG] switch_core_state_machine.c:603 (sofia/default/7120041412@phone.c2a.com.au) State NEW
  7. 2018-07-27 17:23:39.433640 [DEBUG] sofia.c:2334 detaching session f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  8. 2018-07-27 17:23:39.453618 [DEBUG] sofia.c:2442 Re-attaching to session f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  9. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:9873 sofia/default/7120041412@phone.c2a.com.au receiving invite from 103.239.77.180:1491 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  10. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:10044 IP 103.239.77.180 Rejected by acl "default". Falling back to Digest auth.
  11. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:11168 Setting NAT mode based on via received
  12. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [received][100]
  13. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:7094 Remote SDP:
  14. v=0
  15. o=7120041412 8000 8000 IN IP4 192.168.10.192
  16. s=SIP Call
  17. c=IN IP4 192.168.10.192
  18. t=0 0
  19. m=audio 5004 RTP/AVP 0 8 4 18 2 97 101
  20. a=rtpmap:0 PCMU/8000
  21. a=rtpmap:8 PCMA/8000
  22. a=rtpmap:4 G723/8000
  23. a=rtpmap:18 G729/8000
  24. a=fmtp:18 annexb=no
  25. a=rtpmap:2 G726-32/8000
  26. a=rtpmap:97 iLBC/8000
  27. a=fmtp:97 mode=20
  28. a=rtpmap:101 telephone-event/8000
  29. a=fmtp:101 0-16,32-36,54
  30. a=ptime:20
  31.  
  32. 2018-07-27 17:23:39.473617 [DEBUG] sofia.c:7480 (sofia/default/7120041412@phone.c2a.com.au) State Change CS_NEW -> CS_INIT
  33. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:584 (sofia/default/7120041412@phone.c2a.com.au) Running State Change CS_INIT (Cur 9 Tot 970429)
  34. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:627 (sofia/default/7120041412@phone.c2a.com.au) State INIT
  35. 2018-07-27 17:23:39.473617 [DEBUG] mod_sofia.c:90 sofia/default/7120041412@phone.c2a.com.au SOFIA INIT
  36. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:40 sofia/default/7120041412@phone.c2a.com.au Standard INIT
  37. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:48 (sofia/default/7120041412@phone.c2a.com.au) State Change CS_INIT -> CS_ROUTING
  38. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:627 (sofia/default/7120041412@phone.c2a.com.au) State INIT going to sleep
  39. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:584 (sofia/default/7120041412@phone.c2a.com.au) Running State Change CS_ROUTING (Cur 9 Tot 970429)
  40. 2018-07-27 17:23:39.473617 [DEBUG] switch_channel.c:2249 (sofia/default/7120041412@phone.c2a.com.au) Callstate Change DOWN -> RINGING
  41. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:643 (sofia/default/7120041412@phone.c2a.com.au) State ROUTING
  42. 2018-07-27 17:23:39.473617 [DEBUG] mod_sofia.c:143 sofia/default/7120041412@phone.c2a.com.au SOFIA ROUTING
  43. 2018-07-27 17:23:39.473617 [DEBUG] switch_core_state_machine.c:236 sofia/default/7120041412@phone.c2a.com.au Standard ROUTING
  44. 2018-07-27 17:23:39.473617 [INFO] mod_dialplan_xml.c:637 Processing 7120041412 <7120041412>->1300368909 in context default
  45. 2018-07-27 17:23:39.493630 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fae38b91ff0 Connected.
  46. 2018-07-27 17:23:39.493630 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  47. 2018-07-27 17:23:39.493630 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [xml_handler] Params:
  48. 'Event-Name: REQUEST_PARAMS
  49. Core-UUID: 2715576f-0c4c-4efc-8e55-f1fc5da2dfd8
  50. FreeSWITCH-Hostname: primary
  51. FreeSWITCH-Switchname: primary
  52. FreeSWITCH-IPv4: 103.226.53.20
  53. FreeSWITCH-IPv6: %3A%3A1
  54. Event-Date-Local: 2018-07-27%2017%3A23%3A39
  55. Event-Date-GMT: Fri,%2027%20Jul%202018%2007%3A23%3A39%20GMT
  56. Event-Date-Timestamp: 1532676219473617
  57. Event-Calling-File: mod_dialplan_xml.c
  58. Event-Calling-Function: dialplan_xml_locate
  59. Event-Calling-Line-Number: 608
  60. Event-Sequence: 59862398
  61. Channel-State: CS_ROUTING
  62. Channel-Call-State: RINGING
  63. Channel-State-Number: 2
  64. Channel-Name: sofia/default/7120041412%40phone.c2a.com.au
  65. Unique-ID: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  66. Call-Direction: inbound
  67. Presence-Call-Direction: inbound
  68. Channel-HIT-Dialplan: true
  69. Channel-Presence-ID: 7120041412%40phone.c2a.com.au
  70. Channel-Call-UUID: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  71. Answer-State: ringing
  72. Caller-Direction: inbound
  73. Caller-Logical-Direction: inbound
  74. Caller-Username: 7120041412
  75. Caller-Dialplan: XML
  76. Caller-Caller-ID-Name: 7120041412
  77. Caller-Caller-ID-Number: 7120041412
  78. Caller-Orig-Caller-ID-Name: 7120041412
  79. Caller-Orig-Caller-ID-Number: 7120041412
  80. Caller-Network-Addr: 103.239.77.180
  81. Caller-ANI: 7120041412
  82. Caller-Destination-Number: 1300368909
  83. Caller-Unique-ID: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  84. Caller-Source: mod_sofia
  85. Caller-Context: default
  86. Caller-Channel-Name: sofia/default/7120041412%40phone.c2a.com.au
  87. Caller-Profile-Index: 1
  88. Caller-Profile-Created-Time: 1532676219473617
  89. Caller-Channel-Created-Time: 1532676219473617
  90. Caller-Channel-Answered-Time: 0
  91. Caller-Channel-Progress-Time: 0
  92. Caller-Channel-Progress-Media-Time: 0
  93. Caller-Channel-Hangup-Time: 0
  94. Caller-Channel-Transfer-Time: 0
  95. Caller-Channel-Resurrect-Time: 0
  96. Caller-Channel-Bridged-Time: 0
  97. Caller-Channel-Last-Hold: 0
  98. Caller-Channel-Hold-Accum: 0
  99. Caller-Screen-Bit: true
  100. Caller-Privacy-Hide-Name: false
  101. Caller-Privacy-Hide-Number: false
  102. variable_direction: inbound
  103. variable_uuid: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  104. variable_session_id: 970429
  105. variable_sip_from_user: 7120041412
  106. variable_sip_from_uri: 7120041412%40phone.c2a.com.au
  107. variable_sip_from_host: phone.c2a.com.au
  108. variable_video_media_flow: sendrecv
  109. variable_audio_media_flow: sendrecv
  110. variable_channel_name: sofia/default/7120041412%40phone.c2a.com.au
  111. variable_sip_call_id: 1288767206-5060-6%40BJC.BGI.BA.BJC
  112. variable_ep_codec_string: CORE_PCM_MODULE.PCMU%408000h%4020i%4064000b,CORE_PCM_MODULE.PCMA%408000h%4020i%4064000b,mod_g729.G729%408000h%4020i%408000b
  113. variable_sip_local_network_addr: 103.226.53.19
  114. variable_sip_network_ip: 103.239.77.180
  115. variable_sip_network_port: 1491
  116. variable_sip_invite_stamp: 1532676219473617
  117. variable_sip_received_ip: 103.239.77.180
  118. variable_sip_received_port: 1491
  119. variable_sip_via_protocol: udp
  120. variable_sip_authorized: true
  121. variable_Event-Name: REQUEST_PARAMS
  122. variable_Core-UUID: 2715576f-0c4c-4efc-8e55-f1fc5da2dfd8
  123. variable_FreeSWITCH-Hostname: primary
  124. variable_FreeSWITCH-Switchname: primary
  125. variable_FreeSWITCH-IPv4: 103.226.53.20
  126. variable_FreeSWITCH-IPv6: %3A%3A1
  127. variable_Event-Date-Local: 2018-07-27%2017%3A23%3A39
  128. variable_Event-Date-GMT: Fri,%2027%20Jul%202018%2007%3A23%3A39%20GMT
  129. variable_Event-Date-Timestamp: 1532676219473617
  130. variable_Event-Calling-File: sofia.c
  131. variable_Event-Calling-Function: sofia_handle_sip_i_invite
  132. variable_Event-Calling-Line-Number: 10096
  133. variable_Event-Sequence: 59862391
  134. variable_sip_number_alias: 7120041412
  135. variable_sip_auth_username: 7120041412
  136. variable_sip_auth_realm: phone.c2a.com.au
  137. variable_number_alias: 7120041412
  138. variable_requested_user_name: 7120041412
  139. variable_requested_domain_name: 103.226.53.20
  140. variable_effective_caller_id_name: 0265162229
  141. variable_effective_caller_id_number: 0265162229
  142. variable_sipcall: true
  143. variable_accountcode: 98055562
  144. variable_user_name: 7120041412
  145. variable_domain_name: 103.226.53.20
  146. variable_sip_from_user_stripped: 7120041412
  147. variable_sip_from_tag: 760539825
  148. variable_sofia_profile_name: default
  149. variable_recovery_profile_name: default
  150. variable_sip_P-Preferred-Identity: %3Csip%3A7120041412%40phone.c2a.com.au%3E
  151. variable_sip_cid_type: pid
  152. variable_sip_full_route: %3Csip%3Aphone.c2a.com.au%3A5060%3Blr%3E
  153. variable_sip_full_via: SIP/2.0/UDP%20192.168.10.192%3A5060%3Bbranch%3Dz9hG4bK673446469%3Brport%3D1491%3Breceived%3D103.239.77.180
  154. variable_sip_full_from: %3Csip%3A7120041412%40phone.c2a.com.au%3E%3Btag%3D760539825
  155. variable_sip_full_to: %3Csip%3A1300368909%40phone.c2a.com.au%3E
  156. variable_sip_allow: INVITE,%20ACK,%20OPTIONS,%20CANCEL,%20BYE,%20SUBSCRIBE,%20NOTIFY,%20INFO,%20REFER,%20UPDATE
  157. variable_sip_req_user: 1300368909
  158. variable_sip_req_uri: 1300368909%40phone.c2a.com.au
  159. variable_sip_req_host: phone.c2a.com.au
  160. variable_sip_to_user: 1300368909
  161. variable_sip_to_uri: 1300368909%40phone.c2a.com.au
  162. variable_sip_to_host: phone.c2a.com.au
  163. variable_sip_contact_user: 7120041412
  164. variable_sip_contact_port: 5060
  165. variable_sip_contact_uri: 7120041412%40192.168.10.192%3A5060
  166. variable_sip_contact_host: 192.168.10.192
  167. variable_rtp_use_codec_string: PCMA,PCMU,G729,G722
  168. variable_sip_user_agent: Grandstream%20HT701%201.0.10.3
  169. variable_sip_via_host: 192.168.10.192
  170. variable_sip_via_port: 5060
  171. variable_sip_via_rport: 1491
  172. variable_max_forwards: 70
  173. variable_presence_id: 7120041412%40phone.c2a.com.au
  174. variable_sip_Privacy: none
  175. variable_sip_nat_detected: true
  176. variable_switch_r_sdp: v%3D0%0D%0Ao%3D7120041412%208000%208000%20IN%20IP4%20192.168.10.192%0D%0As%3DSIP%20Call%0D%0Ac%3DIN%20IP4%20192.168.10.192%0D%0At%3D0%200%0D%0Am%3Daudio%205004%20RTP/AVP%200%208%204%2018%202%2097%20101%0D%0Aa%3Drtpmap%3A0%20PCMU/8000%0D%0Aa%3Drtpmap%3A8%20PCMA/8000%0D%0Aa%3Drtpmap%3A4%20G723/8000%0D%0Aa%3Drtpmap%3A18%20G729/8000%0D%0Aa%3Dfmtp%3A18%20annexb%3Dno%0D%0Aa%3Drtpmap%3A2%20G726-32/8000%0D%0Aa%3Drtpmap%3A97%20iLBC/8000%0D%0Aa%3Dfmtp%3A97%20mode%3D20%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-16,32-36,54%0D%0Aa%3Dptime%3A20%0D%0A
  177. variable_endpoint_disposition: PROXY%20MEDIA
  178. variable_call_uuid: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  179. Hunt-Direction: inbound
  180. Hunt-Logical-Direction: inbound
  181. Hunt-Username: 7120041412
  182. Hunt-Dialplan: XML
  183. Hunt-Caller-ID-Name: 7120041412
  184. Hunt-Caller-ID-Number: 7120041412
  185. Hunt-Orig-Caller-ID-Name: 7120041412
  186. Hunt-Orig-Caller-ID-Number: 7120041412
  187. Hunt-Network-Addr: 103.239.77.180
  188. Hunt-ANI: 7120041412
  189. Hunt-Destination-Number: 1300368909
  190. Hunt-Unique-ID: f5ec8f6c-1155-481b-8cfe-1d29dc6631ee
  191. Hunt-Source: mod_sofia
  192. Hunt-Context: default
  193. Hunt-Channel-Name: sofia/default/7120041412%40phone.c2a.com.au
  194. Hunt-Profile-Index: 1
  195. Hunt-Profile-Created-Time: 1532676219473617
  196. Hunt-Channel-Created-Time: 1532676219473617
  197. Hunt-Channel-Answered-Time: 0
  198. Hunt-Channel-Progress-Time: 0
  199. Hunt-Channel-Progress-Media-Time: 0
  200. Hunt-Channel-Hangup-Time: 0
  201. Hunt-Channel-Transfer-Time: 0
  202. Hunt-Channel-Resurrect-Time: 0
  203. Hunt-Channel-Bridged-Time: 0
  204. Hunt-Channel-Last-Hold: 0
  205. Hunt-Channel-Hold-Accum: 0
  206. Hunt-Screen-Bit: true
  207. Hunt-Privacy-Hide-Name: false
  208. Hunt-Privacy-Hide-Number: false
  209.  
  210. '
  211. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_value:
  212. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] key_name:
  213. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] tag_name:
  214. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [xml_REQUEST] section: dialplan
  215. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : 1300368909
  216. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  :  /
  217. 2018-07-27 17:23:39.493630 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,B.id as accountid,B.number as account_code,B.did_cid_translation as did_cid_translation,A.number as  did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.always,A.always_destination,A.always_vm_flag,A.user_busy_vm_flag,A.user_not_registered_vm_flag,A.no_answer_vm_flag,A.leg_timeout FROM dids AS A,accounts AS B WHERE A.status=0 AND B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1300368909" LIMIT 1
  218. 2018-07-27 17:23:39.493630 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1300368909" limit 1
  219. 2018-07-27 17:23:39.493630 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
  220. 2018-07-27 17:23:39.493630 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Accountcode : 98055562]
  221. 2018-07-27 17:23:39.493630 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "98055562" OR id="98055562") AND status=0 AND deleted=0 AND (expiry >= '2018-07-27 07:23:39' OR expiry = '0000-00-00 00:00:00') limit 1
  222. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns ='^1300368909.*' OR blocked_patterns ='^130036890.*' OR blocked_patterns ='^13003689.*' OR blocked_patterns ='^1300368.*' OR blocked_patterns ='^130036.*' OR blocked_patterns ='^13003.*' OR blocked_patterns ='^1300.*' OR blocked_patterns ='^130.*' OR blocked_patterns ='^13.*' OR blocked_patterns ='^1.*' OR blocked_patterns ='--') AND accountid = 28 limit 1
  223. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT * FROM packages  as P inner join package_patterns as PKGPTR on P.id = PKGPTR.package_id WHERE (patterns ='^1300368909.*' OR patterns ='^130036890.*' OR patterns ='^13003689.*' OR patterns ='^1300368.*' OR patterns ='^130036.*' OR patterns ='^13003.*' OR patterns ='^1300.*' OR patterns ='^130.*' OR patterns ='^13.*' OR patterns ='^1.*' OR patterns ='--') AND status = 0 AND pricelist_id = 3 ORDER BY LENGTH(PKGPTR.patterns) DESC LIMIT 1
  224. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Account Information ===================
  225. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] User id : 28
  226. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Account code : 98055562
  227. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Balance : 99868.8506
  228. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
  229. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Ratecard id : 3
  230. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================================================
  231. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 3 AND status = 0
  232. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern ='^1300368909.*' OR pattern ='^130036890.*' OR pattern ='^13003689.*' OR pattern ='^1300368.*' OR pattern ='^130036.*' OR pattern ='^13003.*' OR pattern ='^1300.*' OR pattern ='^130.*' OR pattern ='^13.*' OR pattern ='^1.*' OR pattern ='--') AND status = 0 AND pricelist_id = 3  ORDER BY LENGTH(pattern) DESC,cost DESC LIMIT 1
  233. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Rates Information ===================
  234. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 2362
  235. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.35000
  236. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Includedseconds : 0
  237. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Cost : 0.00000
  238. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] comment : 13 numbers
  239. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Accid : 28
  240. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] ================================================================
  241. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] [FIND_MAXLENGTH] Call is free - assigning max length!!! :: 100
  242. 2018-07-27 17:23:39.513610 [INFO] switch_cpp.cpp:1365 [ASTPP] Call Max length duration : 100 minutes
  243. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=3
  244. 2018-07-27 17:23:39.513610 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TK.cid_translation,TR.init_inc,TK.status,TK.dialed_modify,TK.maxchannels,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern ='^1300368909.*' OR pattern ='^130036890.*' OR pattern ='^13003689.*' OR pattern ='^1300368.*' OR pattern ='^130036.*' OR pattern ='^13003.*' OR pattern ='^1300.*' OR pattern ='^130.*' OR pattern ='^13.*' OR pattern ='^1.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (2,3) ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence
  245. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Termination Rates Information ===================
  246. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 2362
  247. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : ^13.*
  248. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : 13 numbers
  249. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.00000
  250. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Free Seconds : 0
  251. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Prefix : ^13.*
  252. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Strip :
  253. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Prepend :
  254. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Carrier id : 3
  255. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] carrier_name : IBOSS
  256. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] dialplan_variable :
  257. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Failover gateway :
  258. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Vendor id : 155
  259. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Number Translation :
  260. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Max channels : 0
  261. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================END OF TERMINATION RATES=======================
  262. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] =============== Termination Rates Information ===================
  263. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] ID : 6
  264. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Code : ^13.*
  265. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Destination : Australia
  266. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Connectcost : 0.35000
  267. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Free Seconds : 0
  268. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Prefix : ^13.*
  269. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Strip :
  270. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Prepend :
  271. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Carrier id : 2
  272. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] carrier_name : ivox
  273. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] dialplan_variable :
  274. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Failover gateway :
  275. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Vendor id : 154
  276. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Number Translation :
  277. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] Max channels : 0
  278. 2018-07-27 17:23:39.533614 [INFO] switch_cpp.cpp:1365 [ASTPP] ========================END OF TERMINATION RATES=======================
  279. 2018-07-27 17:23:39.533614 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 28 AND status=0 LIMIT 1
  280. 2018-07-27 17:23:39.533614 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLOUTBOUND] Caller ID Translation Starts
  281. 2018-07-27 17:23:39.533614 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLOUTBOUND] Caller ID Translation Ends
  282. 2018-07-27 17:23:39.533614 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLOUTBOUND] Caller ID Translation Starts
  283. 2018-07-27 17:23:39.533614 [WARNING] switch_cpp.cpp:1365 [ASTPP] [FSXMLOUTBOUND] Caller ID Translation Ends
  284. 2018-07-27 17:23:39.533614 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Generated XML:
  285. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  286. <document type="freeswitch/xml">
  287. <section name="dialplan" description="ASTPP Dialplan">
  288. <context name="default">
  289. <extension name="1300368909">
  290. <condition field="destination_number" expression="1300368909">
  291. <action application="set" data="effective_destination_number=1300368909"/>
  292. <action application="sched_hangup" data="+6000 normal_clearing"/>
  293. <action application="set" data="callstart=2018-07-27 07:23:39"/>
  294. <action application="set" data="hangup_after_bridge=true"/>
  295. <action application="set" data="continue_on_fail=true"/>
  296. <extension name="limit_exceeded">
  297. <condition field="destination_number" expression="^limit_exceeded$">
  298. <action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/Au-Busy-15s.wav"/>
  299. <action application="hangup"/>
  300. </condition>
  301. </extension>
  302. <action application="set" data="account_id=28"/>
  303. <action application="set" data="parent_id=0"/>
  304. <action application="set" data="entity_id=0"/>
  305. <action application="set" data="call_processed=internal"/>
  306. <action application="set" data="call_direction=outbound"/>
  307. <action application="set" data="accountname=default"/>
  308. <action application="export" data="t38_passthru=true"/>
  309. <action application="set" data="fax_enable_t38=true"/>
  310. <action application="set" data="fax_enable_t38_request=true"/>
  311. <action application="set" data="origination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:3|MARKUP:0|ACCID:28"/>
  312.  
  313. <action application="set" data="calltype=STANDARD"/>
  314. <action application="set" data="termination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:155"/>
  315. <action application="set" data="trunk_id=3"/>
  316. <action application="set" data="provider_id=155"/>
  317. <action application="bridge" data="[leg_timeout=0]sofia/gateway/IBOSS/1300368909"/>
  318. <action application="set" data="calltype=STANDARD"/>
  319. <action application="set" data="termination_rates=ID:6|CODE:^13.*|DESTINATION:Australia|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:2|PROVIDER:154"/>
  320. <action application="set" data="trunk_id=2"/>
  321. <action application="set" data="provider_id=154"/>
  322. <action application="bridge" data="[leg_timeout=0]sofia/gateway/ivox/1300368909"/>
  323. </condition>
  324. </extension>
  325. </context>
  326. </section>
  327. </document>
  328. 2018-07-27 17:23:39.533614 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fae38b91ff0 released.
  329. Dialplan: sofia/default/7120041412@phone.c2a.com.au parsing [default->1300368909] continue=false
  330. Dialplan: sofia/default/7120041412@phone.c2a.com.au Regex (PASS) [1300368909] destination_number(1300368909) =~ /1300368909/ break=on-false
  331. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(effective_destination_number=1300368909)
  332. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action sched_hangup(+6000 normal_clearing)
  333. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(callstart=2018-07-27 07:23:39)
  334. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(hangup_after_bridge=true)
  335. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(continue_on_fail=true)
  336. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(account_id=28)
  337. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(parent_id=0)
  338. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(entity_id=0)
  339. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(call_processed=internal)
  340. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(call_direction=outbound)
  341. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(accountname=default)
  342. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action export(t38_passthru=true)
  343. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(fax_enable_t38=true)
  344. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(fax_enable_t38_request=true)
  345. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(origination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:3|MARKUP:0|ACCID:28)
  346. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(calltype=STANDARD)
  347. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(termination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:155)
  348. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(trunk_id=3)
  349. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(provider_id=155)
  350. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action bridge([leg_timeout=0]sofia/gateway/IBOSS/1300368909)
  351. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(calltype=STANDARD)
  352. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(termination_rates=ID:6|CODE:^13.*|DESTINATION:Australia|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:2|PROVIDER:154)
  353. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(trunk_id=2)
  354. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action set(provider_id=154)
  355. Dialplan: sofia/default/7120041412@phone.c2a.com.au Action bridge([leg_timeout=0]sofia/gateway/ivox/1300368909)
  356. 2018-07-27 17:23:39.533614 [DEBUG] switch_core_state_machine.c:286 (sofia/default/7120041412@phone.c2a.com.au) State Change CS_ROUTING -> CS_EXECUTE
  357. 2018-07-27 17:23:39.533614 [DEBUG] switch_core_state_machine.c:643 (sofia/default/7120041412@phone.c2a.com.au) State ROUTING going to sleep
  358. 2018-07-27 17:23:39.533614 [DEBUG] switch_core_state_machine.c:584 (sofia/default/7120041412@phone.c2a.com.au) Running State Change CS_EXECUTE (Cur 9 Tot 970429)
  359. 2018-07-27 17:23:39.533614 [DEBUG] switch_core_state_machine.c:650 (sofia/default/7120041412@phone.c2a.com.au) State EXECUTE
  360. 2018-07-27 17:23:39.533614 [DEBUG] mod_sofia.c:198 sofia/default/7120041412@phone.c2a.com.au SOFIA EXECUTE
  361. 2018-07-27 17:23:39.533614 [DEBUG] switch_core_state_machine.c:328 sofia/default/7120041412@phone.c2a.com.au Standard EXECUTE
  362. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(effective_destination_number=1300368909)
  363. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [effective_destination_number]=[1300368909]
  364. EXECUTE sofia/default/7120041412@phone.c2a.com.au sched_hangup(+6000 normal_clearing)
  365. 2018-07-27 17:23:39.533614 [DEBUG] switch_scheduler.c:249 Added task 394839 switch_ivr_schedule_hangup (f5ec8f6c-1155-481b-8cfe-1d29dc6631ee) to run at 1532682219
  366. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(callstart=2018-07-27 07:23:39)
  367. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [callstart]=[2018-07-27 07:23:39]
  368. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(hangup_after_bridge=true)
  369. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [hangup_after_bridge]=[true]
  370. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(continue_on_fail=true)
  371. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [continue_on_fail]=[true]
  372. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(account_id=28)
  373. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [account_id]=[28]
  374. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(parent_id=0)
  375. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [parent_id]=[0]
  376. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(entity_id=0)
  377. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [entity_id]=[0]
  378. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(call_processed=internal)
  379. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [call_processed]=[internal]
  380. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(call_direction=outbound)
  381. 2018-07-27 17:23:39.533614 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [call_direction]=[outbound]
  382. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(accountname=default)
  383. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [accountname]=[default]
  384. EXECUTE sofia/default/7120041412@phone.c2a.com.au export(t38_passthru=true)
  385. 2018-07-27 17:23:39.553605 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true]
  386. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(fax_enable_t38=true)
  387. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [fax_enable_t38]=[true]
  388. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(fax_enable_t38_request=true)
  389. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [fax_enable_t38_request]=[true]
  390. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(origination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:3|MARKUP:0|ACCID:28)
  391. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [origination_rates]=[ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.35000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:3|MARKUP:0|ACCID:28]
  392. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(calltype=STANDARD)
  393. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [calltype]=[STANDARD]
  394. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(termination_rates=ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:155)
  395. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [termination_rates]=[ID:2362|CODE:^13.*|DESTINATION:13 numbers|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:155]
  396. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(trunk_id=3)
  397. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [trunk_id]=[3]
  398. EXECUTE sofia/default/7120041412@phone.c2a.com.au set(provider_id=155)
  399. 2018-07-27 17:23:39.553605 [DEBUG] mod_dptools.c:1548 SET sofia/default/7120041412@phone.c2a.com.au [provider_id]=[155]
  400. EXECUTE sofia/default/7120041412@phone.c2a.com.au bridge([leg_timeout=0]sofia/gateway/IBOSS/1300368909)
  401. 2018-07-27 17:23:39.553605 [DEBUG] switch_channel.c:1250 sofia/default/7120041412@phone.c2a.com.au EXPORTING[export_vars] [t38_passthru]=[true] to event
  402. 2018-07-27 17:23:39.553605 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  403. 2018-07-27 17:23:39.553605 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  404. 2018-07-27 17:23:39.553605 [NOTICE] switch_channel.c:1104 New Channel sofia/default/1300368909 [ee7e546f-879b-43f0-8f34-291657ecc7ce]
  405. 2018-07-27 17:23:39.553605 [DEBUG] mod_sofia.c:4819 (sofia/default/1300368909) State Change CS_NEW -> CS_INIT
  406. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:584 (sofia/default/1300368909) Running State Change CS_INIT (Cur 10 Tot 970430)
  407. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:627 (sofia/default/1300368909) State INIT
  408. 2018-07-27 17:23:39.553605 [DEBUG] mod_sofia.c:90 sofia/default/1300368909 SOFIA INIT
  409. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_media.c:9710 sofia/default/1300368909 Patched SDP
  410. ---
  411. v=0
  412. o=7120041412 8000 8000 IN IP4 192.168.10.192
  413. s=SIP Call
  414. c=IN IP4 192.168.10.192
  415. t=0 0
  416. m=audio 5004 RTP/AVP 0 8 4 18 2 97 101
  417. a=rtpmap:0 PCMU/8000
  418. a=rtpmap:8 PCMA/8000
  419. a=rtpmap:4 G723/8000
  420. a=rtpmap:18 G729/8000
  421. a=fmtp:18 annexb=no
  422. a=rtpmap:2 G726-32/8000
  423. a=rtpmap:97 iLBC/8000
  424. a=fmtp:97 mode=20
  425. a=rtpmap:101 telephone-event/8000
  426. a=fmtp:101 0-16,32-36,54
  427. a=ptime:20
  428.  
  429. +++
  430. v=0
  431. o=FreeSWITCH 1936332568 1936332569 IN IP4 103.226.53.19
  432. s=FreeSWITCH
  433. c=IN IP4 103.226.53.19
  434. t=0 0
  435. m=audio 28450 RTP/AVP 0 8 4 18 2 97 101
  436. a=rtpmap:0 PCMU/8000
  437. a=rtpmap:8 PCMA/8000
  438. a=rtpmap:4 G723/8000
  439. a=rtpmap:18 G729/8000
  440. a=fmtp:18 annexb=no
  441. a=rtpmap:2 G726-32/8000
  442. a=rtpmap:97 iLBC/8000
  443. a=fmtp:97 mode=20
  444. a=rtpmap:101 telephone-event/8000
  445. a=fmtp:101 0-16,32-36,54
  446. a=ptime:20
  447.  
  448. 2018-07-27 17:23:39.553605 [DEBUG] sofia_glue.c:1295 sofia/default/1300368909 sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  449. Local SDP:
  450. v=0
  451. o=FreeSWITCH 1936332568 1936332569 IN IP4 103.226.53.19
  452. s=FreeSWITCH
  453. c=IN IP4 103.226.53.19
  454. t=0 0
  455. m=audio 28450 RTP/AVP 0 8 4 18 2 97 101
  456. a=rtpmap:0 PCMU/8000
  457. a=rtpmap:8 PCMA/8000
  458. a=rtpmap:4 G723/8000
  459. a=rtpmap:18 G729/8000
  460. a=fmtp:18 annexb=no
  461. a=rtpmap:2 G726-32/8000
  462. a=rtpmap:97 iLBC/8000
  463. a=fmtp:97 mode=20
  464. a=rtpmap:101 telephone-event/8000
  465. a=fmtp:101 0-16,32-36,54
  466. a=ptime:20
  467.  
  468. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:40 sofia/default/1300368909 Standard INIT
  469. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:48 (sofia/default/1300368909) State Change CS_INIT -> CS_ROUTING
  470. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:627 (sofia/default/1300368909) State INIT going to sleep
  471. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:584 (sofia/default/1300368909) Running State Change CS_ROUTING (Cur 10 Tot 970430)
  472. 2018-07-27 17:23:39.553605 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [calling][0]
  473. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:643 (sofia/default/1300368909) State ROUTING
  474. 2018-07-27 17:23:39.553605 [DEBUG] mod_sofia.c:143 sofia/default/1300368909 SOFIA ROUTING
  475. 2018-07-27 17:23:39.553605 [DEBUG] switch_ivr_originate.c:67 (sofia/default/1300368909) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  476. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:643 (sofia/default/1300368909) State ROUTING going to sleep
  477. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:584 (sofia/default/1300368909) Running State Change CS_CONSUME_MEDIA (Cur 10 Tot 970430)
  478. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:662 (sofia/default/1300368909) State CONSUME_MEDIA
  479. 2018-07-27 17:23:39.553605 [DEBUG] switch_core_state_machine.c:662 (sofia/default/1300368909) State CONSUME_MEDIA going to sleep
  480. 2018-07-27 17:23:40.433613 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [98056383@phone.c2a.com.au] from ip 103.239.76.212
  481. 2018-07-27 17:23:41.413608 [DEBUG] sofia.c:6170 Ping to sip user '6217448486@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  482. 2018-07-27 17:23:41.413608 [DEBUG] sofia.c:6170 Ping to sip user '8567825548@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  483. 2018-07-27 17:23:41.413608 [DEBUG] sofia.c:6170 Ping to sip user '6786926165@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  484. 2018-07-27 17:23:41.413608 [DEBUG] sofia.c:6170 Ping to sip user '5990414253@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  485. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '7120041412@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  486. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '4686909652@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  487. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '8080062168@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  488. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '2372167153@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  489. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '5837699930@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  490. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '5573548337@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  491. 2018-07-27 17:23:41.433608 [DEBUG] sofia.c:6170 Ping to sip user '4176375620@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  492. 2018-07-27 17:23:41.453632 [DEBUG] sofia.c:6170 Ping to sip user '2649153346@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  493. 2018-07-27 17:23:41.453632 [DEBUG] sofia.c:6170 Ping to sip user '3580807566@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  494. 2018-07-27 17:23:42.273642 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [proceeding][183]
  495. 2018-07-27 17:23:42.273642 [DEBUG] sofia.c:7094 Remote SDP:
  496. v=0
  497. o=- 340844292 340844292 IN IP4 125.213.162.112
  498. s=ENSResip
  499. c=IN IP4 125.213.162.116
  500. t=0 0
  501. m=audio 24082 RTP/AVP 0 101
  502. a=rtpmap:0 PCMU/8000
  503. a=rtpmap:101 telephone-event/8000
  504. a=fmtp:101 0-15
  505. a=ptime:20
  506. a=silenceSupp:off - - - -
  507.  
  508. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:9710 sofia/default/1300368909 Patched SDP
  509. ---
  510. v=0
  511. o=FreeSWITCH 1936332568 1936332569 IN IP4 103.226.53.19
  512. s=FreeSWITCH
  513. c=IN IP4 103.226.53.19
  514. t=0 0
  515. m=audio 28450 RTP/AVP 0 8 4 18 2 97 101
  516. a=rtpmap:0 PCMU/8000
  517. a=rtpmap:8 PCMA/8000
  518. a=rtpmap:4 G723/8000
  519. a=rtpmap:18 G729/8000
  520. a=fmtp:18 annexb=no
  521. a=rtpmap:2 G726-32/8000
  522. a=rtpmap:97 iLBC/8000
  523. a=fmtp:97 mode=20
  524. a=rtpmap:101 telephone-event/8000
  525. a=fmtp:101 0-16,32-36,54
  526. a=ptime:20
  527.  
  528. +++
  529. v=0
  530. o=FreeSWITCH 1936332568 1936332570 IN IP4 103.226.53.19
  531. s=FreeSWITCH
  532. c=IN IP4 103.226.53.19
  533. t=0 0
  534. m=audio 28450 RTP/AVP 0 8 4 18 2 97 101
  535. a=rtpmap:0 PCMU/8000
  536. a=rtpmap:8 PCMA/8000
  537. a=rtpmap:4 G723/8000
  538. a=rtpmap:18 G729/8000
  539. a=fmtp:18 annexb=no
  540. a=rtpmap:2 G726-32/8000
  541. a=rtpmap:97 iLBC/8000
  542. a=fmtp:97 mode=20
  543. a=rtpmap:101 telephone-event/8000
  544. a=fmtp:101 0-16,32-36,54
  545. a=ptime:20
  546.  
  547. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/1300368909 PROXY/0 0 ms 160 samples 0 bits 1 channels
  548. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_codec.c:111 sofia/default/1300368909 Original read codec set to PROXY:0
  549. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:6944 PROXY AUDIO RTP [sofia/default/1300368909] 125.213.162.116:24082->125.213.162.116:24082 codec: 0 ms: 20
  550. 2018-07-27 17:23:42.273642 [DEBUG] switch_rtp.c:4133 Not using a timer
  551. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:7179 sofia/default/1300368909 Set 2833 dtmf send payload to 101
  552. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:7186 sofia/default/1300368909 Set 2833 dtmf receive payload to 101
  553. 2018-07-27 17:23:42.273642 [DEBUG] switch_core_media.c:7209 sofia/default/1300368909 Set rtp dtmf delay to 40
  554. 2018-07-27 17:23:42.273642 [NOTICE] sofia.c:7266 Pre-Answer sofia/default/1300368909!
  555. 2018-07-27 17:23:42.273642 [DEBUG] switch_channel.c:3474 (sofia/default/1300368909) Callstate Change DOWN -> EARLY
  556. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:9710 sofia/default/7120041412@phone.c2a.com.au Patched SDP
  557. ---
  558. v=0
  559. o=- 340844292 340844292 IN IP4 125.213.162.112
  560. s=ENSResip
  561. c=IN IP4 125.213.162.116
  562. t=0 0
  563. m=audio 24082 RTP/AVP 0 101
  564. a=rtpmap:0 PCMU/8000
  565. a=rtpmap:101 telephone-event/8000
  566. a=fmtp:101 0-15
  567. a=ptime:20
  568. a=silenceSupp:off - - - -
  569.  
  570. +++
  571. v=0
  572. o=FreeSWITCH 1936428031 1936428032 IN IP4 103.226.53.19
  573. s=FreeSWITCH
  574. c=IN IP4 103.226.53.19
  575. t=0 0
  576. m=audio 20570 RTP/AVP 0 101
  577. a=rtpmap:0 PCMU/8000
  578. a=rtpmap:101 telephone-event/8000
  579. a=fmtp:101 0-15
  580. a=ptime:20
  581. a=silenceSupp:off - - - -
  582.  
  583. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/7120041412@phone.c2a.com.au PROXY/0 0 ms 160 samples 0 bits 1 channels
  584. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_codec.c:111 sofia/default/7120041412@phone.c2a.com.au Original read codec set to PROXY:0
  585. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:6944 PROXY AUDIO RTP [sofia/default/7120041412@phone.c2a.com.au] 192.168.10.192:5004->192.168.10.192:5004 codec: 0 ms: 20
  586. 2018-07-27 17:23:42.293618 [DEBUG] switch_rtp.c:4133 Not using a timer
  587. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:7179 sofia/default/7120041412@phone.c2a.com.au Set 2833 dtmf send payload to 101
  588. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:7186 sofia/default/7120041412@phone.c2a.com.au Set 2833 dtmf receive payload to 101
  589. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:7209 sofia/default/7120041412@phone.c2a.com.au Set rtp dtmf delay to 40
  590. 2018-07-27 17:23:42.293618 [INFO] switch_ivr_originate.c:3654 Sending early media
  591. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:9710 sofia/default/7120041412@phone.c2a.com.au Patched SDP
  592. ---
  593. v=0
  594. o=- 340844292 340844292 IN IP4 125.213.162.112
  595. s=ENSResip
  596. c=IN IP4 125.213.162.116
  597. t=0 0
  598. m=audio 24082 RTP/AVP 0 101
  599. a=rtpmap:0 PCMU/8000
  600. a=rtpmap:101 telephone-event/8000
  601. a=fmtp:101 0-15
  602. a=ptime:20
  603. a=silenceSupp:off - - - -
  604.  
  605. +++
  606. v=0
  607. o=FreeSWITCH 1936428031 1936428033 IN IP4 103.226.53.19
  608. s=FreeSWITCH
  609. c=IN IP4 103.226.53.19
  610. t=0 0
  611. m=audio 20570 RTP/AVP 0 101
  612. a=rtpmap:0 PCMU/8000
  613. a=rtpmap:101 telephone-event/8000
  614. a=fmtp:101 0-15
  615. a=ptime:20
  616. a=silenceSupp:off - - - -
  617.  
  618. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/7120041412@phone.c2a.com.au.
  619. 2018-07-27 17:23:42.293618 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/7120041412@phone.c2a.com.au!
  620. 2018-07-27 17:23:42.293618 [DEBUG] switch_channel.c:3474 (sofia/default/7120041412@phone.c2a.com.au) Callstate Change RINGING -> EARLY
  621. 2018-07-27 17:23:42.293618 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/default/1300368909]
  622. 2018-07-27 17:23:42.293618 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [early][183]
  623. 2018-07-27 17:23:42.293618 [DEBUG] switch_ivr_bridge.c:1614 (sofia/default/1300368909) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  624. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_state_machine.c:584 (sofia/default/1300368909) Running State Change CS_EXCHANGE_MEDIA (Cur 10 Tot 970430)
  625. 2018-07-27 17:23:42.293618 [DEBUG] switch_core_state_machine.c:653 (sofia/default/1300368909) State EXCHANGE_MEDIA
  626. 2018-07-27 17:23:42.293618 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  627. 2018-07-27 17:23:42.293618 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  628. 2018-07-27 17:23:42.573788 [INFO] switch_rtp.c:7231 Auto Changing audio port from 192.168.10.192:5004 to 103.239.77.180:5004
  629. 2018-07-27 17:23:43.433629 [DEBUG] sofia.c:6170 Ping to sip user '1803015463@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  630. 2018-07-27 17:23:43.433629 [DEBUG] sofia.c:6170 Ping to sip user '7993449808@103.226.53.20' succeeded with code 200 - count 3, state Reachable
  631. 2018-07-27 17:23:43.593620 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [completing][200]
  632. 2018-07-27 17:23:43.593620 [DEBUG] sofia.c:7091 Duplicate SDP
  633. v=0
  634. o=- 340844292 340844292 IN IP4 125.213.162.112
  635. s=ENSResip
  636. c=IN IP4 125.213.162.116
  637. t=0 0
  638. m=audio 24082 RTP/AVP 0 101
  639. a=rtpmap:0 PCMU/8000
  640. a=rtpmap:101 telephone-event/8000
  641. a=fmtp:101 0-15
  642. a=ptime:20
  643. a=silenceSupp:off - - - -
  644.  
  645. 2018-07-27 17:23:43.593620 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [ready][200]
  646. 2018-07-27 17:23:43.593620 [NOTICE] sofia.c:8180 Channel [sofia/default/1300368909] has been answered
  647. 2018-07-27 17:23:43.593620 [DEBUG] switch_channel.c:3773 (sofia/default/1300368909) Callstate Change EARLY -> ACTIVE
  648. 2018-07-27 17:23:43.593620 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/1300368909.
  649. 2018-07-27 17:23:43.593620 [DEBUG] switch_core_media.c:9710 sofia/default/7120041412@phone.c2a.com.au Patched SDP
  650. ---
  651. v=0
  652. o=- 340844292 340844292 IN IP4 125.213.162.112
  653. s=ENSResip
  654. c=IN IP4 125.213.162.116
  655. t=0 0
  656. m=audio 24082 RTP/AVP 0 101
  657. a=rtpmap:0 PCMU/8000
  658. a=rtpmap:101 telephone-event/8000
  659. a=fmtp:101 0-15
  660. a=ptime:20
  661. a=silenceSupp:off - - - -
  662.  
  663. +++
  664. v=0
  665. o=FreeSWITCH 1936428031 1936428034 IN IP4 103.226.53.19
  666. s=FreeSWITCH
  667. c=IN IP4 103.226.53.19
  668. t=0 0
  669. m=audio 20570 RTP/AVP 0 101
  670. a=rtpmap:0 PCMU/8000
  671. a=rtpmap:101 telephone-event/8000
  672. a=fmtp:101 0-15
  673. a=ptime:20
  674. a=silenceSupp:off - - - -
  675.  
  676. 2018-07-27 17:23:43.593620 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/7120041412@phone.c2a.com.au.
  677. 2018-07-27 17:23:43.593620 [NOTICE] switch_ivr.c:779 Channel [sofia/default/7120041412@phone.c2a.com.au] has been answered
  678. 2018-07-27 17:23:43.593620 [DEBUG] switch_channel.c:3773 (sofia/default/7120041412@phone.c2a.com.au) Callstate Change EARLY -> ACTIVE
  679. 2018-07-27 17:23:43.593620 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [completed][200]
  680. 2018-07-27 17:23:43.613622 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  681. 2018-07-27 17:23:43.613622 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  682. 2018-07-27 17:23:43.713672 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [ready][200]
  683. 2018-07-27 17:23:43.713672 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/7120041412@phone.c2a.com.au.
  684. 2018-07-27 17:23:44.453618 [DEBUG] sofia.c:6170 Ping to sip user '98084069@103.226.53.20' succeeded with code 200 - count 3, state Reachable
  685. 2018-07-27 17:23:45.433611 [DEBUG] sofia.c:6170 Ping to sip user '2114812383@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  686. 2018-07-27 17:23:45.433611 [DEBUG] sofia.c:6170 Ping to sip user '6330123028@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  687. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '4936172231@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  688. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '8463161113@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  689. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '6072475659@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  690. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '3116364723@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  691. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '5750271407@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  692. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '7623336111@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  693. 2018-07-27 17:23:45.453597 [DEBUG] sofia.c:6170 Ping to sip user '6615257118@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  694. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '9024007661@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  695. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '5069530460@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  696. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '3159861784@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  697. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '1419067075@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  698. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '5378582960@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  699. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '4118115760@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  700. 2018-07-27 17:23:45.473592 [DEBUG] sofia.c:6170 Ping to sip user '6663049871@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  701. 2018-07-27 17:23:45.773612 [DEBUG] sofia.c:7084 Channel sofia/default/8779617009@phone.c2a.com.au entering state [terminated][487]
  702. 2018-07-27 17:23:45.773612 [NOTICE] sofia.c:8273 Hangup sofia/default/8779617009@phone.c2a.com.au [CS_EXECUTE] [ORIGINATOR_CANCEL]
  703. 2018-07-27 17:23:45.773612 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/8779617009@phone.c2a.com.au]
  704. 2018-07-27 17:23:45.793611 [DEBUG] switch_ivr_bridge.c:706 sofia/default/8779617009@phone.c2a.com.au ending bridge by request from write function
  705. 2018-07-27 17:23:45.793611 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/0280211244]
  706. 2018-07-27 17:23:45.793611 [NOTICE] switch_ivr_bridge.c:900 Hangup sofia/default/0280211244 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
  707. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:653 (sofia/default/0280211244) State EXCHANGE_MEDIA going to sleep
  708. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0280211244) Running State Change CS_HANGUP (Cur 10 Tot 970430)
  709. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:850 (sofia/default/0280211244) Callstate Change EARLY -> HANGUP
  710. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0280211244) State HANGUP
  711. 2018-07-27 17:23:45.793611 [DEBUG] mod_sofia.c:432 sofia/default/0280211244 Overriding SIP cause 487 with 487 from the other leg
  712. 2018-07-27 17:23:45.793611 [DEBUG] mod_sofia.c:438 Channel sofia/default/0280211244 hanging up, cause: ORIGINATOR_CANCEL
  713. 2018-07-27 17:23:45.793611 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/0280211244 skip receive message [UNBRIDGE] (channel is hungup already)
  714. 2018-07-27 17:23:45.793611 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/8779617009@phone.c2a.com.au skip receive message [UNBRIDGE] (channel is hungup already)
  715. 2018-07-27 17:23:45.793611 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/default/0280211244
  716. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:60 sofia/default/0280211244 Standard HANGUP, cause: ORIGINATOR_CANCEL
  717. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0280211244) State HANGUP going to sleep
  718. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:619 (sofia/default/0280211244) State Change CS_HANGUP -> CS_REPORTING
  719. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0280211244) Running State Change CS_REPORTING (Cur 10 Tot 970430)
  720. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_session.c:2815 sofia/default/8779617009@phone.c2a.com.au skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  721. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:650 (sofia/default/8779617009@phone.c2a.com.au) State EXECUTE going to sleep
  722. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:584 (sofia/default/8779617009@phone.c2a.com.au) Running State Change CS_HANGUP (Cur 10 Tot 970430)
  723. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0280211244) State REPORTING
  724. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8779617009@phone.c2a.com.au) Callstate Change EARLY -> HANGUP
  725. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:852 (sofia/default/8779617009@phone.c2a.com.au) State HANGUP
  726. 2018-07-27 17:23:45.793611 [DEBUG] mod_sofia.c:438 Channel sofia/default/8779617009@phone.c2a.com.au hanging up, cause: ORIGINATOR_CANCEL
  727. 2018-07-27 17:23:45.793611 [INFO] mod_json_cdr.c:271 Process [da4ed662-3e17-47d0-a09e-3d9cbac891af.cdr.json]
  728. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:60 sofia/default/8779617009@phone.c2a.com.au Standard HANGUP, cause: ORIGINATOR_CANCEL
  729. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:852 (sofia/default/8779617009@phone.c2a.com.au) State HANGUP going to sleep
  730. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:619 (sofia/default/8779617009@phone.c2a.com.au) State Change CS_HANGUP -> CS_REPORTING
  731. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:584 (sofia/default/8779617009@phone.c2a.com.au) Running State Change CS_REPORTING (Cur 10 Tot 970430)
  732. 2018-07-27 17:23:45.793611 [DEBUG] switch_core_state_machine.c:938 (sofia/default/8779617009@phone.c2a.com.au) State REPORTING
  733. 2018-07-27 17:23:45.793611 [INFO] mod_json_cdr.c:271 Process [370d44cc-105a-4323-a31e-0efe1fc2d40f.cdr.json]
  734. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:174 sofia/default/0280211244 Standard REPORTING, cause: ORIGINATOR_CANCEL
  735. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0280211244) State REPORTING going to sleep
  736. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:610 (sofia/default/0280211244) State Change CS_REPORTING -> CS_DESTROY
  737. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_session.c:1665 Session 970428 (sofia/default/0280211244) Locked, Waiting on external entities
  738. 2018-07-27 17:23:45.853624 [NOTICE] switch_core_session.c:1683 Session 970428 (sofia/default/0280211244) Ended
  739. 2018-07-27 17:23:45.853624 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/0280211244 [CS_DESTROY]
  740. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:741 (sofia/default/0280211244) Running State Change CS_DESTROY (Cur 9 Tot 970430)
  741. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0280211244) State DESTROY
  742. 2018-07-27 17:23:45.853624 [DEBUG] mod_sofia.c:343 sofia/default/0280211244 SOFIA DESTROY
  743. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:181 sofia/default/0280211244 Standard DESTROY
  744. 2018-07-27 17:23:45.853624 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0280211244) State DESTROY going to sleep
  745. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:174 sofia/default/8779617009@phone.c2a.com.au Standard REPORTING, cause: ORIGINATOR_CANCEL
  746. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:938 (sofia/default/8779617009@phone.c2a.com.au) State REPORTING going to sleep
  747. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:610 (sofia/default/8779617009@phone.c2a.com.au) State Change CS_REPORTING -> CS_DESTROY
  748. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_session.c:1665 Session 970427 (sofia/default/8779617009@phone.c2a.com.au) Locked, Waiting on external entities
  749. 2018-07-27 17:23:45.933620 [NOTICE] switch_core_session.c:1683 Session 970427 (sofia/default/8779617009@phone.c2a.com.au) Ended
  750. 2018-07-27 17:23:45.933620 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/8779617009@phone.c2a.com.au [CS_DESTROY]
  751. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:741 (sofia/default/8779617009@phone.c2a.com.au) Running State Change CS_DESTROY (Cur 8 Tot 970430)
  752. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/8779617009@phone.c2a.com.au) State DESTROY
  753. 2018-07-27 17:23:45.933620 [DEBUG] mod_sofia.c:343 sofia/default/8779617009@phone.c2a.com.au SOFIA DESTROY
  754. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:181 sofia/default/8779617009@phone.c2a.com.au Standard DESTROY
  755. 2018-07-27 17:23:45.933620 [DEBUG] switch_core_state_machine.c:751 (sofia/default/8779617009@phone.c2a.com.au) State DESTROY going to sleep
  756. 2018-07-27 17:23:46.033639 [DEBUG] switch_scheduler.c:144 Deleting task 394838 switch_ivr_schedule_hangup (370d44cc-105a-4323-a31e-0efe1fc2d40f)
  757. 2018-07-27 17:23:47.433685 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [7993449808@phone.c2a.com.au] from ip 103.226.53.102
  758. 2018-07-27 17:23:48.033625 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [8852604137@phone.c2a.com.au] from ip 103.226.53.161
  759. freeswitch@primary>
  760. freeswitch@primary>
  761. 2018-07-27 17:23:48.933633 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [9631869658@phone.c2a.com.au] from ip 207.136.3.80
  762. freeswitch@primary>
  763. freeswitch@primary>
  764. freeswitch@primary>
  765. freeswitch@primary>
  766. freeswitch@primary>
  767. freeswitch@primary>
  768. freeswitch@primary>
  769. 2018-07-27 17:23:49.153640 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [8703498704@phone.c2a.com.au] from ip 103.239.77.250
  770. freeswitch@primary>
  771. freeswitch@primary>
  772. freeswitch@primary>
  773. freeswitch@primary>
  774. freeswitch@primary>
  775. freeswitch@primary>
  776. freeswitch@primary>
  777. freeswitch@primary>
  778. freeswitch@primary>
  779. 2018-07-27 17:23:50.433629 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [received][100]
  780. 2018-07-27 17:23:50.433629 [DEBUG] sofia.c:7094 Remote SDP:
  781. v=0
  782. o=- 340844292 340844293 IN IP4 125.213.162.112
  783. s=ENSResip
  784. c=IN IP4 125.213.162.116
  785. t=0 0
  786. m=image 24462 udptl t38
  787. a=T38FaxVersion:0
  788. a=T38MaxBitRate:9600
  789. a=T38FaxMaxBuffer:200
  790. a=T38FaxMaxDatagram:72
  791. a=T38FaxRateManagement:transferredTCF
  792. a=T38FaxUdpEC:t38UDPRedundancy
  793. a=T38FaxFillBitRemoval:0
  794. a=T38FaxTranscodingMMR:0
  795. a=T38FaxTranscodingJBIG:0
  796.  
  797. 2018-07-27 17:23:50.433629 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/1300368909.
  798. 2018-07-27 17:23:50.433629 [DEBUG] switch_core_media.c:6291 AUDIO RTP CHANGING DEST TO: [125.213.162.116:24462]
  799. 2018-07-27 17:23:50.433629 [DEBUG] sofia.c:7830 Passing SDP to other leg.
  800. v=0
  801. o=- 340844292 340844293 IN IP4 125.213.162.112
  802. s=ENSResip
  803. c=IN IP4 125.213.162.116
  804. t=0 0
  805. m=image 24462 udptl t38
  806. a=T38FaxVersion:0
  807. a=T38MaxBitRate:9600
  808. a=T38FaxMaxBuffer:200
  809. a=T38FaxMaxDatagram:72
  810. a=T38FaxRateManagement:transferredTCF
  811. a=T38FaxUdpEC:t38UDPRedundancy
  812. a=T38FaxFillBitRemoval:0
  813. a=T38FaxTranscodingMMR:0
  814. a=T38FaxTranscodingJBIG:0
  815.  
  816. 2018-07-27 17:23:50.453649 [DEBUG] mod_sofia.c:1487 sofia/default/7120041412@phone.c2a.com.au Sending media re-direct:
  817. v=0
  818. o=- 340844292 340844293 IN IP4 125.213.162.112
  819. s=ENSResip
  820. c=IN IP4 125.213.162.116
  821. t=0 0
  822. m=image 24462 udptl t38
  823. a=T38FaxVersion:0
  824. a=T38MaxBitRate:9600
  825. a=T38FaxMaxBuffer:200
  826. a=T38FaxMaxDatagram:72
  827. a=T38FaxRateManagement:transferredTCF
  828. a=T38FaxUdpEC:t38UDPRedundancy
  829. a=T38FaxFillBitRemoval:0
  830. a=T38FaxTranscodingMMR:0
  831. a=T38FaxTranscodingJBIG:0
  832.  
  833. 2018-07-27 17:23:50.453649 [DEBUG] switch_core_media.c:6269 Remote address:port [192.168.10.192:5004] has not changed.
  834. 2018-07-27 17:23:50.453649 [DEBUG] switch_core_media.c:9710 sofia/default/7120041412@phone.c2a.com.au Patched SDP
  835. ---
  836. v=0
  837. o=- 340844292 340844293 IN IP4 125.213.162.112
  838. s=ENSResip
  839. c=IN IP4 125.213.162.116
  840. t=0 0
  841. m=image 24462 udptl t38
  842. a=T38FaxVersion:0
  843. a=T38MaxBitRate:9600
  844. a=T38FaxMaxBuffer:200
  845. a=T38FaxMaxDatagram:72
  846. a=T38FaxRateManagement:transferredTCF
  847. a=T38FaxUdpEC:t38UDPRedundancy
  848. a=T38FaxFillBitRemoval:0
  849. a=T38FaxTranscodingMMR:0
  850. a=T38FaxTranscodingJBIG:0
  851.  
  852. +++
  853. v=0
  854. o=FreeSWITCH 1936428031 1936428035 IN IP4 103.226.53.19
  855. s=FreeSWITCH
  856. c=IN IP4 103.226.53.19
  857. t=0 0
  858. m=image 20570 udptl t38
  859. a=T38FaxVersion:0
  860. a=T38MaxBitRate:9600
  861. a=T38FaxMaxBuffer:200
  862. a=T38FaxMaxDatagram:72
  863. a=T38FaxRateManagement:transferredTCF
  864. a=T38FaxUdpEC:t38UDPRedundancy
  865. a=T38FaxFillBitRemoval:0
  866. a=T38FaxTranscodingMMR:0
  867. a=T38FaxTranscodingJBIG:0
  868.  
  869. 2018-07-27 17:23:50.453649 [DEBUG] sofia_glue.c:1295 sofia/default/7120041412@phone.c2a.com.au sending invite version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  870. Local SDP:
  871. v=0
  872. o=FreeSWITCH 1936428031 1936428035 IN IP4 103.226.53.19
  873. s=FreeSWITCH
  874. c=IN IP4 103.226.53.19
  875. t=0 0
  876. m=image 20570 udptl t38
  877. a=T38FaxVersion:0
  878. a=T38MaxBitRate:9600
  879. a=T38FaxMaxBuffer:200
  880. a=T38FaxMaxDatagram:72
  881. a=T38FaxRateManagement:transferredTCF
  882. a=T38FaxUdpEC:t38UDPRedundancy
  883. a=T38FaxFillBitRemoval:0
  884. a=T38FaxTranscodingMMR:0
  885. a=T38FaxTranscodingJBIG:0
  886.  
  887. 2018-07-27 17:23:50.453649 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [calling][0]
  888. 2018-07-27 17:23:50.513609 [DEBUG] switch_core_media.c:6269 Remote address:port [192.168.10.192:5004] has not changed.
  889. 2018-07-27 17:23:50.513609 [DEBUG] sofia.c:6654 Passing 200 OK to other leg
  890. v=0
  891. o=7120041412 8000 8001 IN IP4 192.168.10.192
  892. s=SIP Call
  893. c=IN IP4 192.168.10.192
  894. t=0 0
  895. m=image 5004 udptl t38
  896. a=T38FaxVersion:0
  897. a=T38MaxBitRate:9600
  898. a=T38FaxRateManagement:transferredTCF
  899. a=T38FaxMaxBuffer:200
  900. a=T38FaxMaxDatagram:72
  901. a=T38FaxUdpEC:t38UDPRedundancy
  902.  
  903. 2018-07-27 17:23:50.513609 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [completing][200]
  904. 2018-07-27 17:23:50.513609 [DEBUG] sofia.c:7091 Duplicate SDP
  905. v=0
  906. o=7120041412 8000 8001 IN IP4 192.168.10.192
  907. s=SIP Call
  908. c=IN IP4 192.168.10.192
  909. t=0 0
  910. m=image 5004 udptl t38
  911. a=T38FaxVersion:0
  912. a=T38MaxBitRate:9600
  913. a=T38FaxRateManagement:transferredTCF
  914. a=T38FaxMaxBuffer:200
  915. a=T38FaxMaxDatagram:72
  916. a=T38FaxUdpEC:t38UDPRedundancy
  917.  
  918. 2018-07-27 17:23:50.513609 [DEBUG] mod_sofia.c:2132 Responding with 200 [OK]
  919. 2018-07-27 17:23:50.513609 [DEBUG] switch_core_media.c:9710 sofia/default/1300368909 Patched SDP
  920. ---
  921. v=0
  922. o=7120041412 8000 8001 IN IP4 192.168.10.192
  923. s=SIP Call
  924. c=IN IP4 192.168.10.192
  925. t=0 0
  926. m=image 5004 udptl t38
  927. a=T38FaxVersion:0
  928. a=T38MaxBitRate:9600
  929. a=T38FaxRateManagement:transferredTCF
  930. a=T38FaxMaxBuffer:200
  931. a=T38FaxMaxDatagram:72
  932. a=T38FaxUdpEC:t38UDPRedundancy
  933.  
  934. +++
  935. v=0
  936. o=FreeSWITCH 1936332568 1936332571 IN IP4 103.226.53.19
  937. s=FreeSWITCH
  938. c=IN IP4 103.226.53.19
  939. t=0 0
  940. m=image 28450 udptl t38
  941. a=T38FaxVersion:0
  942. a=T38MaxBitRate:9600
  943. a=T38FaxRateManagement:transferredTCF
  944. a=T38FaxMaxBuffer:200
  945. a=T38FaxMaxDatagram:72
  946. a=T38FaxUdpEC:t38UDPRedundancy
  947.  
  948. 2018-07-27 17:23:50.513609 [DEBUG] switch_core_media.c:6269 Remote address:port [125.213.162.116:24462] has not changed.
  949. 2018-07-27 17:23:50.513609 [DEBUG] sofia.c:7084 Channel sofia/default/7120041412@phone.c2a.com.au entering state [ready][200]
  950. 2018-07-27 17:23:50.513609 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [completed][200]
  951. 2018-07-27 17:23:50.513609 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/default/7120041412@phone.c2a.com.au.
  952. 2018-07-27 17:23:50.593610 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  953. 2018-07-27 17:23:50.613612 [DEBUG] sofia.c:7084 Channel sofia/default/1300368909 entering state [ready][200]
  954. 2018-07-27 17:23:51.713635 [WARNING] sofia_reg.c:2906 Can't find user [1878568962@103.226.53.20] from 103.239.77.38
  955. You must define a domain called '103.226.53.20' in your directory and add a user with the id="1878568962" attribute
  956. and you must configure your device to use the proper domain in it's authentication credentials.
  957. 2018-07-27 17:23:51.713635 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [1878568962@phone.c2a.com.au] from ip 103.239.77.38
  958. 2018-07-27 17:23:53.453622 [DEBUG] sofia.c:6170 Ping to sip user '2124127137@103.226.53.20' succeeded with code 200 - count 3, state Reachable
  959. 2018-07-27 17:23:53.473659 [DEBUG] sofia.c:6170 Ping to sip user '98064628@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  960. 2018-07-27 17:23:53.473659 [DEBUG] sofia.c:6170 Ping to sip user '3742291443@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  961. 2018-07-27 17:23:53.473659 [DEBUG] sofia.c:6170 Ping to sip user '7849275805@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  962. 2018-07-27 17:23:53.473659 [DEBUG] sofia.c:6170 Ping to sip user '4401164399@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  963. 2018-07-27 17:23:53.473659 [DEBUG] sofia.c:6170 Ping to sip user '8062035925@103.226.53.20' succeeded with code 200 - count 3, state Reachable
  964. 2018-07-27 17:23:53.493626 [DEBUG] sofia.c:6170 Ping to sip user '6841655420@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  965. 2018-07-27 17:23:53.513657 [DEBUG] sofia.c:6170 Ping to sip user '98062003@103.226.53.20' succeeded with code 200 - count 3, state Reachable
  966. 2018-07-27 17:23:56.533605 [DEBUG] sofia.c:6170 Ping to sip user '98062117@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  967. 2018-07-27 17:23:57.493621 [DEBUG] sofia.c:6170 Ping to sip user '8779491582@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  968. freeswitch@primary>
  969. freeswitch@primary>
  970. freeswitch@primary>
  971. 2018-07-27 17:23:58.493637 [DEBUG] sofia.c:6170 Ping to sip user '2188581467@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  972. 2018-07-27 17:24:02.493639 [DEBUG] sofia.c:6170 Ping to sip user '4381068958@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  973. 2018-07-27 17:24:02.493639 [DEBUG] sofia.c:6170 Ping to sip user '98060082@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  974. 2018-07-27 17:24:02.493639 [DEBUG] sofia.c:6170 Ping to sip user '4250559859@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  975. 2018-07-27 17:24:02.833616 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'default' for [5748841388@phone.c2a.com.au] from ip 103.239.76.201
  976. 2018-07-27 17:24:02.873624 [WARNING] sofia_reg.c:2906 Can't find user [5748841388@103.226.53.20] from 103.239.76.201
  977. You must define a domain called '103.226.53.20' in your directory and add a user with the id="5748841388" attribute
  978. and you must configure your device to use the proper domain in it's authentication credentials.
  979. 2018-07-27 17:24:02.873624 [WARNING] sofia_reg.c:1737 SIP auth failure (REGISTER) on sofia profile 'default' for [5748841388@phone.c2a.com.au] from ip 103.239.76.201
  980. 2018-07-27 17:24:06.493619 [DEBUG] sofia.c:6170 Ping to sip user '1803015463@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  981. 2018-07-27 17:24:06.513658 [DEBUG] sofia.c:6170 Ping to sip user '8841961777@103.226.53.20' succeeded with code 200 - count 2, state Reachable
  982. 2018-07-27 17:24:06.513658 [DEBUG] sofia.c:6170 Ping to sip user '1552424269@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  983. 2018-07-27 17:24:06.533618 [DEBUG] sofia.c:6170 Ping to sip user '8703498704@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  984. 2018-07-27 17:24:06.553618 [DEBUG] sofia.c:6170 Ping to sip user '9631869658@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  985. 2018-07-27 17:24:07.493633 [DEBUG] sofia.c:6170 Ping to sip user '7568975362@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  986. 2018-07-27 17:24:08.533623 [WARNING] sofia.c:6146 Sip user '4222645691@103.226.53.20' is now Unreachable
  987. 2018-07-27 17:24:08.533623 [WARNING] sofia.c:6157 Expire sip user '4222645691@103.226.53.20' due to options failure
  988. 2018-07-27 17:24:08.573618 [WARNING] sofia.c:6146 Sip user '2220907642@103.226.53.20' is now Unreachable
  989. 2018-07-27 17:24:08.573618 [WARNING] sofia.c:6157 Expire sip user '2220907642@103.226.53.20' due to options failure
  990. 2018-07-27 17:24:09.533630 [DEBUG] sofia.c:6170 Ping to sip user '9157676046@103.226.53.20' succeeded with code 200 - count 1, state Reachable
  991. 2018-07-27 17:24:09.973618 [NOTICE] sofia.c:1012 Hangup sofia/default/anonymous@phone.c2a.com.au [CS_EXECUTE] [NORMAL_CLEARING]
  992. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:706 sofia/default/anonymous@phone.c2a.com.au ending bridge by request from write function
  993. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/0448484662]
  994. 2018-07-27 17:24:09.973618 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/default/0448484662 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  995. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:653 (sofia/default/0448484662) State EXCHANGE_MEDIA going to sleep
  996. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0448484662) Running State Change CS_HANGUP (Cur 8 Tot 970430)
  997. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:850 (sofia/default/0448484662) Callstate Change ACTIVE -> HANGUP
  998. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0448484662) State HANGUP
  999. 2018-07-27 17:24:09.973618 [DEBUG] mod_sofia.c:432 sofia/default/0448484662 Overriding SIP cause 480 with 200 from the other leg
  1000. 2018-07-27 17:24:09.973618 [DEBUG] mod_sofia.c:438 Channel sofia/default/0448484662 hanging up, cause: NORMAL_CLEARING
  1001. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:706 sofia/default/0448484662 ending bridge by request from write function
  1002. 2018-07-27 17:24:09.973618 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/default/0448484662
  1003. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:60 sofia/default/0448484662 Standard HANGUP, cause: NORMAL_CLEARING
  1004. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:852 (sofia/default/0448484662) State HANGUP going to sleep
  1005. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:619 (sofia/default/0448484662) State Change CS_HANGUP -> CS_REPORTING
  1006. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:584 (sofia/default/0448484662) Running State Change CS_REPORTING (Cur 8 Tot 970430)
  1007. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/default/anonymous@phone.c2a.com.au]
  1008. 2018-07-27 17:24:09.973618 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0448484662) State REPORTING
  1009. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:1712 sofia/default/0448484662 skip receive message [UNBRIDGE] (channel is hungup already)
  1010. 2018-07-27 17:24:09.973618 [DEBUG] switch_ivr_bridge.c:1715 sofia/default/anonymous@phone.c2a.com.au skip receive message [UNBRIDGE] (channel is hungup already)
  1011. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_session.c:2815 sofia/default/anonymous@phone.c2a.com.au skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1012. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:650 (sofia/default/anonymous@phone.c2a.com.au) State EXECUTE going to sleep
  1013. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:584 (sofia/default/anonymous@phone.c2a.com.au) Running State Change CS_HANGUP (Cur 8 Tot 970430)
  1014. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:850 (sofia/default/anonymous@phone.c2a.com.au) Callstate Change ACTIVE -> HANGUP
  1015. 2018-07-27 17:24:09.993604 [INFO] mod_json_cdr.c:271 Process [4bc60738-066e-4b3c-bca8-21b459cd350a.cdr.json]
  1016. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:852 (sofia/default/anonymous@phone.c2a.com.au) State HANGUP
  1017. 2018-07-27 17:24:09.993604 [DEBUG] mod_sofia.c:438 Channel sofia/default/anonymous@phone.c2a.com.au hanging up, cause: NORMAL_CLEARING
  1018. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:60 sofia/default/anonymous@phone.c2a.com.au Standard HANGUP, cause: NORMAL_CLEARING
  1019. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:852 (sofia/default/anonymous@phone.c2a.com.au) State HANGUP going to sleep
  1020. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:619 (sofia/default/anonymous@phone.c2a.com.au) State Change CS_HANGUP -> CS_REPORTING
  1021. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:584 (sofia/default/anonymous@phone.c2a.com.au) Running State Change CS_REPORTING (Cur 8 Tot 970430)
  1022. 2018-07-27 17:24:09.993604 [DEBUG] switch_core_state_machine.c:938 (sofia/default/anonymous@phone.c2a.com.au) State REPORTING
  1023. 2018-07-27 17:24:09.993604 [INFO] mod_json_cdr.c:271 Process [8d622834-bca6-4e7f-bebd-bfb238df269f.cdr.json]
  1024. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:174 sofia/default/0448484662 Standard REPORTING, cause: NORMAL_CLEARING
  1025. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:938 (sofia/default/0448484662) State REPORTING going to sleep
  1026. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:610 (sofia/default/0448484662) State Change CS_REPORTING -> CS_DESTROY
  1027. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_session.c:1665 Session 970424 (sofia/default/0448484662) Locked, Waiting on external entities
  1028. 2018-07-27 17:24:10.053636 [NOTICE] switch_core_session.c:1683 Session 970424 (sofia/default/0448484662) Ended
  1029. 2018-07-27 17:24:10.053636 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/0448484662 [CS_DESTROY]
  1030. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:741 (sofia/default/0448484662) Running State Change CS_DESTROY (Cur 7 Tot 970430)
  1031. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0448484662) State DESTROY
  1032. 2018-07-27 17:24:10.053636 [DEBUG] mod_sofia.c:343 sofia/default/0448484662 SOFIA DESTROY
  1033. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:181 sofia/default/0448484662 Standard DESTROY
  1034. 2018-07-27 17:24:10.053636 [DEBUG] switch_core_state_machine.c:751 (sofia/default/0448484662) State DESTROY going to sleep
  1035. 2018-07-27 17:24:10.093621 [DEBUG] switch_core_state_machine.c:174 sofia/default/anonymous@phone.c2a.com.au Standard REPORTING, cause: NORMAL_CLEARING
  1036. 2018-07-27 17:24:10.093621 [DEBUG] switch_core_state_machine.c:938 (sofia/default/anonymous@phone.c2a.com.au) State REPORTING going to sleep
  1037. 2018-07-27 17:24:10.113620 [DEBUG] switch_core_state_machine.c:610 (sofia/default/anonymous@phone.c2a.com.au) State Change CS_REPORTING -> CS_DESTROY
  1038.  

Reply to "Untitled"