FreeSWITCH Pastebin

Untitled

From Bulky Shama, 6 Years ago, written in Plain Text.
- go back
URL https://pastebin.freeswitch.org/view/b8d50fc3
Embed
Viewing differences between and Untitled
  1. +OK log level  [7]
  2. 2018-12-08 12:35:30.112024 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [6d77aa66-a0e4-49d6-8cd4-53329204ec0c]
  3. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 76)
  4. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  5. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted.
  6. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100]
  7. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7094 Remote SDP:
  8. v=0
  9. o=root 178137107 178137107 IN IP4 87.238.166.51
  10. s=SpeakUp Gateway
  11. c=IN IP4 87.238.166.51
  12. t=0 0
  13. m=audio 12582 RTP/AVP 8 0 101
  14. a=rtpmap:8 PCMA/8000
  15. a=rtpmap:0 PCMU/8000
  16. a=rtpmap:101 telephone-event/8000
  17. a=fmtp:101 0-16
  18. a=ptime:20
  19. a=nortpproxy:yes
  20.  
  21. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  22. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  23. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  24. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  25. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  26. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  27. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  28. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  29. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  30. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  31. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8
  32. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  33. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101
  34. 2018-12-08 12:35:30.112024 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT
  35. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW
  36. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 76)
  37. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT
  38. 2018-12-08 12:35:30.112024 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT
  39. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT
  40. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING
  41. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep
  42. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 76)
  43. 2018-12-08 12:35:30.112024 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING
  44. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING
  45. 2018-12-08 12:35:30.112024 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING
  46. 2018-12-08 12:35:30.112024 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING
  47. 2018-12-08 12:35:30.112024 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default
  48. 2018-12-08 12:35:30.132006 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe684065980 Connected.
  49. 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  50. 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp
  51. 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 32490112449 / 32490112449
  52. 2018-12-08 12:35:30.132006 [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.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 ="fwwwastpp" LIMIT 1
  53. 2018-12-08 12:35:30.132006 [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="fwwwastpp" limit 1
  54. 2018-12-08 12:35:30.132006 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
  55. 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "fwwwastpp"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  56. 2018-12-08 12:35:30.132006 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1
  57. 2018-12-08 12:35:30.132006 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
  58. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  59. <document type="freeswitch/xml">
  60. <section name="dialplan" description="ASTPP Dialplan">
  61. <context name="default">
  62. <extension name="fwwwastpp">
  63. <condition field="destination_number" expression="fwwwastpp">
  64. <action application="log" data="WARNING Accountcode  is not authenticated!!"/>
  65. <action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav"/>
  66. <action application="set" data="original_caller_id_name=32490112449"/>
  67. <action application="set" data="original_caller_id_number=32490112449"/>
  68. <action application="set" data="error_cdr=1"/>
  69. <action application="set" data="callstart=2018-12-08 11:35:30"/>
  70. <action application="set" data="account_id=0"/>
  71. <action application="set" data="call_direction=outbound"/>
  72. <action application="set" data="sip_ignore_remote_cause=true"/>
  73. <action application="set" data="call_processed=internal"/>
  74. <action application="set" data="effective_destination_number=fwwwastpp"/>
  75. <action application="set" data="last_bridge_hangup_cause=AUTHENTICATION_FAIL"/>
  76. <action application="hangup" data="AUTHENTICATION_FAIL"/>
  77. </condition>
  78. </extension>
  79. </context>
  80. </section>
  81. </document>
  82. 2018-12-08 12:35:30.132006 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe684065980 released.
  83. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false
  84. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false
  85. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode  is not authenticated!!)
  86. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  87. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449)
  88. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449)
  89. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1)
  90. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:35:30)
  91. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0)
  92. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound)
  93. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true)
  94. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal)
  95. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp)
  96. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  97. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL)
  98. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE
  99. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep
  100. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 76)
  101. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE
  102. 2018-12-08 12:35:30.132006 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE
  103. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE
  104. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode  is not authenticated!!)
  105. 2018-12-08 12:35:30.132006 [WARNING] mod_dptools.c:1742 Accountcode  is not authenticated!!
  106. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be
  107. 2018-12-08 12:35:30.132006 [INFO] switch_core_session.c:2649 Sending early media
  108. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 25076 -> 87.238.166.51 port 12582 codec: 8 ms: 20
  109. 2018-12-08 12:35:30.132006 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  110. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101
  111. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101
  112. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40
  113. 2018-12-08 12:35:30.132006 [DEBUG] mod_sofia.c:2364 Ring SDP:
  114. v=0
  115. o=FreeSWITCH 1544243854 1544243855 IN IP4 109.68.167.132
  116. s=FreeSWITCH
  117. c=IN IP4 109.68.167.132
  118. t=0 0
  119. m=audio 25076 RTP/AVP 8 101
  120. a=rtpmap:8 PCMA/8000
  121. a=rtpmap:101 telephone-event/8000
  122. a=fmtp:101 0-16
  123. a=ptime:20
  124. a=sendrecv
  125.  
  126. 2018-12-08 12:35:30.132006 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be!
  127. 2018-12-08 12:35:30.132006 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY
  128. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  129. 2018-12-08 12:35:30.132006 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000
  130. 2018-12-08 12:35:30.132006 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  131. 2018-12-08 12:35:30.132006 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183]
  132. 2018-12-08 12:35:30.192020 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  133. 2018-12-08 12:35:31.652014 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav
  134. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449)
  135. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449]
  136. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449)
  137. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449]
  138. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1)
  139. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1]
  140. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:35:30)
  141. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:35:30]
  142. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0)
  143. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0]
  144. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound)
  145. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound]
  146. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true)
  147. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true]
  148. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal)
  149. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal]
  150. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp)
  151. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp]
  152. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  153. 2018-12-08 12:35:31.652014 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL]
  154. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL)
  155. 2018-12-08 12:35:31.652014 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING]
  156. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  157. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep
  158. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 76)
  159. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP
  160. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP
  161. 2018-12-08 12:35:31.652014 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING
  162. 2018-12-08 12:35:31.652014 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
  163. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING
  164. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep
  165. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING
  166. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 76)
  167. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING
  168. 2018-12-08 12:35:31.652014 [INFO] mod_json_cdr.c:271 Process [6d77aa66-a0e4-49d6-8cd4-53329204ec0c.cdr.json]
  169. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING
  170. 2018-12-08 12:35:31.652014 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep
  171. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY
  172. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_session.c:1665 Session 76 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities
  173. 2018-12-08 12:35:31.672002 [NOTICE] switch_core_session.c:1683 Session 76 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended
  174. 2018-12-08 12:35:31.672002 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY]
  175. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 76)
  176. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY
  177. 2018-12-08 12:35:31.672002 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY
  178. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY
  179. 2018-12-08 12:35:31.672002 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep
  180. Type control-D or /exit or /quit or /bye to exit.
  181.  
  182. 2018-12-08 12:41:05.132024 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [8d323a2f-f956-4906-bc4b-5c6bb0ff58b7]
  183. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 77)
  184. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  185. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted.
  186. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100]
  187. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7094 Remote SDP:
  188. v=0
  189. o=root 98860711 98860711 IN IP4 87.238.166.50
  190. s=SpeakUp Gateway
  191. c=IN IP4 87.238.166.50
  192. t=0 0
  193. m=audio 19904 RTP/AVP 8 0 101
  194. a=rtpmap:8 PCMA/8000
  195. a=rtpmap:0 PCMU/8000
  196. a=rtpmap:101 telephone-event/8000
  197. a=fmtp:101 0-16
  198. a=ptime:20
  199. a=nortpproxy:yes
  200.  
  201. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  202. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  203. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  204. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  205. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  206. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  207. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  208. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  209. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  210. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  211. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8
  212. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  213. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101
  214. 2018-12-08 12:41:05.132024 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT
  215. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW
  216. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 77)
  217. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT
  218. 2018-12-08 12:41:05.132024 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT
  219. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT
  220. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING
  221. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep
  222. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 77)
  223. 2018-12-08 12:41:05.132024 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING
  224. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING
  225. 2018-12-08 12:41:05.132024 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING
  226. 2018-12-08 12:41:05.132024 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING
  227. 2018-12-08 12:41:05.132024 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default
  228. 2018-12-08 12:41:05.152020 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe6840661d0 Connected.
  229. 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  230. 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp
  231. 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 32490112449 / 32490112449
  232. 2018-12-08 12:41:05.152020 [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.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 ="fwwwastpp" LIMIT 1
  233. 2018-12-08 12:41:05.152020 [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="fwwwastpp" limit 1
  234. 2018-12-08 12:41:05.152020 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
  235. 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "fwwwastpp"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  236. 2018-12-08 12:41:05.152020 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1
  237. 2018-12-08 12:41:05.152020 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
  238. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  239. <document type="freeswitch/xml">
  240. <section name="dialplan" description="ASTPP Dialplan">
  241. <context name="default">
  242. <extension name="fwwwastpp">
  243. <condition field="destination_number" expression="fwwwastpp">
  244. <action application="log" data="WARNING Accountcode  is not authenticated!!"/>
  245. <action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav"/>
  246. <action application="set" data="original_caller_id_name=32490112449"/>
  247. <action application="set" data="original_caller_id_number=32490112449"/>
  248. <action application="set" data="error_cdr=1"/>
  249. <action application="set" data="callstart=2018-12-08 11:41:05"/>
  250. <action application="set" data="account_id=0"/>
  251. <action application="set" data="call_direction=outbound"/>
  252. <action application="set" data="sip_ignore_remote_cause=true"/>
  253. <action application="set" data="call_processed=internal"/>
  254. <action application="set" data="effective_destination_number=fwwwastpp"/>
  255. <action application="set" data="last_bridge_hangup_cause=AUTHENTICATION_FAIL"/>
  256. <action application="hangup" data="AUTHENTICATION_FAIL"/>
  257. </condition>
  258. </extension>
  259. </context>
  260. </section>
  261. </document>
  262. 2018-12-08 12:41:05.152020 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe6840661d0 released.
  263. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false
  264. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false
  265. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode  is not authenticated!!)
  266. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  267. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449)
  268. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449)
  269. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1)
  270. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:41:05)
  271. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0)
  272. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound)
  273. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true)
  274. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal)
  275. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp)
  276. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  277. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL)
  278. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE
  279. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep
  280. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 77)
  281. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE
  282. 2018-12-08 12:41:05.152020 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE
  283. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE
  284. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode  is not authenticated!!)
  285. 2018-12-08 12:41:05.152020 [WARNING] mod_dptools.c:1742 Accountcode  is not authenticated!!
  286. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be
  287. 2018-12-08 12:41:05.152020 [INFO] switch_core_session.c:2649 Sending early media
  288. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 19718 -> 87.238.166.50 port 19904 codec: 8 ms: 20
  289. 2018-12-08 12:41:05.152020 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  290. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101
  291. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101
  292. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40
  293. 2018-12-08 12:41:05.152020 [DEBUG] mod_sofia.c:2364 Ring SDP:
  294. v=0
  295. o=FreeSWITCH 1544249547 1544249548 IN IP4 109.68.167.132
  296. s=FreeSWITCH
  297. c=IN IP4 109.68.167.132
  298. t=0 0
  299. m=audio 19718 RTP/AVP 8 101
  300. a=rtpmap:8 PCMA/8000
  301. a=rtpmap:101 telephone-event/8000
  302. a=fmtp:101 0-16
  303. a=ptime:20
  304. a=sendrecv
  305.  
  306. 2018-12-08 12:41:05.152020 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be!
  307. 2018-12-08 12:41:05.152020 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY
  308. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  309. 2018-12-08 12:41:05.152020 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000
  310. 2018-12-08 12:41:05.152020 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  311. 2018-12-08 12:41:05.152020 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183]
  312. 2018-12-08 12:41:05.212009 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  313. 2018-12-08 12:41:06.672028 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav
  314. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449)
  315. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449]
  316. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449)
  317. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449]
  318. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1)
  319. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1]
  320. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:41:05)
  321. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:41:05]
  322. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0)
  323. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0]
  324. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound)
  325. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound]
  326. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true)
  327. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true]
  328. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal)
  329. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal]
  330. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp)
  331. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp]
  332. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  333. 2018-12-08 12:41:06.672028 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL]
  334. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL)
  335. 2018-12-08 12:41:06.672028 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING]
  336. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  337. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep
  338. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 77)
  339. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP
  340. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP
  341. 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING
  342. 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
  343. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING
  344. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep
  345. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING
  346. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 77)
  347. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING
  348. 2018-12-08 12:41:06.672028 [INFO] mod_json_cdr.c:271 Process [8d323a2f-f956-4906-bc4b-5c6bb0ff58b7.cdr.json]
  349. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING
  350. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep
  351. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY
  352. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_session.c:1665 Session 77 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities
  353. 2018-12-08 12:41:06.672028 [NOTICE] switch_core_session.c:1683 Session 77 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended
  354. 2018-12-08 12:41:06.672028 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY]
  355. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 77)
  356. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY
  357. 2018-12-08 12:41:06.672028 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY
  358. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY
  359. 2018-12-08 12:41:06.672028 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep
  360. freeswitch@astpp>
  361. freeswitch@astpp>
  362. Type control-D or /exit or /quit or /bye to exit.
  363.  
  364. Type control-D or /exit or /quit or /bye to exit.
  365.  
  366. freeswitch@astpp> show registrationsfreeswitch@astpp>
  367. freeswitch@astpp>
  368. freeswitch@astpp>
  369. freeswitch@astpp> /exit
  370.  
  371. root@astpp:/home/zakariya# fs_cli
  372. .=======================================================.
  373. |            _____ ____     ____ _     ___              |
  374. |           |  ___/ ___|   / ___| |   |_ _|             |
  375. |           | |_  \___ \  | |   | |    | |              |
  376. |           |  _|  ___) | | |___| |___ | |              |
  377. |           |_|   |____/   \____|_____|___|             |
  378. |                                                       |
  379. .=======================================================.
  380. | Anthony Minessale II, Ken Rice,                       |
  381. | Michael Jerris, Travis Cross                          |
  382. | FreeSWITCH (http://www.freeswitch.org)                |
  383. | Paypal Donations Appreciated: paypal@freeswitch.org   |
  384. | Brought to you by ClueCon http://www.cluecon.com/     |
  385. .=======================================================.
  386.  
  387. .=======================================================================================================.
  388. |    ____ _             ____                                                                            |
  389. |   / ___| |_   _  ___ / ___|___  _ __                                                                  |
  390. |  | |   | | | | |/ _ \ |   / _ \| '_ \                                                                 |
  391. |  | |___| | |_| |  __/ |__| (_) | | | |                                                                |
  392. |   \____|_|\__,_|\___|\____\___/|_| |_|                                                                |
  393. |                                                                                                       |
  394. |   _____    _            _                          ____             __                                |
  395. |  |_   _|__| | ___ _ __ | |__   ___  _ __  _   _   / ___|___  _ __  / _| ___ _ __ ___ _ __   ___ ___   |
  396. |    | |/ _ \ |/ _ \ '_ \| '_ \ / _ \| '_ \| | | | | |   / _ \| '_ \| |_ / _ \ '__/ _ \ '_ \ / __/ _ \  |
  397. |    | |  __/ |  __/ |_) | | | | (_) | | | | |_| | | |__| (_) | | | |  _|  __/ | |  __/ | | | (_|  __/  |
  398. |    |_|\___|_|\___| .__/|_| |_|\___/|_| |_|\__, |  \____\___/|_| |_|_|  \___|_|  \___|_| |_|\___\___|  |
  399. |                  |_|                      |___/                                                       |
  400. |   _____                           _                         _                                         |
  401. |  | ____|_   _____ _ __ _   _     / \  _   _  __ _ _   _ ___| |_                                       |
  402. |  |  _| \ \ / / _ \ '__| | | |   / _ \| | | |/ _` | | | / __| __|                                      |
  403. |  | |___ \ V /  __/ |  | |_| |  / ___ \ |_| | (_| | |_| \__ \ |_                                       |
  404. |  |_____| \_/ \___|_|   \__, | /_/   \_\__,_|\__, |\__,_|___/\__|                                      |
  405. |                        |___/                |___/                                                     |
  406. |                                       ____ _             ____                                         |
  407. |  __      ____      ____      __      / ___| |_   _  ___ / ___|___  _ __         ___ ___  _ __ ___     |
  408. |  \ \ /\ / /\ \ /\ / /\ \ /\ / /     | |   | | | | |/ _ \ |   / _ \| '_ \       / __/ _ \| '_ ` _ \    |
  409. |   \ V  V /  \ V  V /  \ V  V /   _  | |___| | |_| |  __/ |__| (_) | | | |  _  | (_| (_) | | | | | |   |
  410. |    \_/\_/    \_/\_/    \_/\_/   (_)  \____|_|\__,_|\___|\____\___/|_| |_| (_)  \___\___/|_| |_| |_|   |
  411. |                                                                                                       |
  412. .=======================================================================================================.
  413.  
  414. Type /help <enter> to see a list of commands
  415.  
  416.  
  417.  
  418. +OK log level  [7]
  419. 2018-12-08 12:47:13.632025 [NOTICE] switch_channel.c:1104 New Channel sofia/default/32490112449@sip.voip.united-telecom.be [0f9c6130-675c-49f6-a7c9-fb7491b5482f]
  420. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_NEW (Cur 1 Tot 78)
  421. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:9873 sofia/default/32490112449@sip.voip.united-telecom.be receiving invite from 87.238.166.8:5060 version: 1.6.19 git 7a77e0b 2017-07-13 12:01:45Z 64bit
  422. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:9989 IP 87.238.166.8 Approved by acl "default[]". Access Granted.
  423. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [received][100]
  424. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7094 Remote SDP:
  425. v=0
  426. o=root 2040389285 2040389285 IN IP4 87.238.166.51
  427. s=SpeakUp Gateway
  428. c=IN IP4 87.238.166.51
  429. t=0 0
  430. m=audio 10548 RTP/AVP 8 0 101
  431. a=rtpmap:8 PCMA/8000
  432. a=rtpmap:0 PCMU/8000
  433. a=rtpmap:101 telephone-event/8000
  434. a=fmtp:101 0-16
  435. a=ptime:20
  436. a=nortpproxy:yes
  437.  
  438. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  439. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  440. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  441. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  442. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  443. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  444. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  445. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  446. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  447. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:3061 Set Codec sofia/default/32490112449@sip.voip.united-telecom.be PCMA/8000 20 ms 160 samples 64000 bits 1 channels
  448. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_codec.c:111 sofia/default/32490112449@sip.voip.united-telecom.be Original read codec set to PCMA:8
  449. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  450. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:4767 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101 recv payload to 101
  451. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7507 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_NEW -> CS_INIT
  452. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:603 (sofia/default/32490112449@sip.voip.united-telecom.be) State NEW
  453. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_INIT (Cur 1 Tot 78)
  454. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT
  455. 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:90 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA INIT
  456. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:40 sofia/default/32490112449@sip.voip.united-telecom.be Standard INIT
  457. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:48 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_INIT -> CS_ROUTING
  458. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:627 (sofia/default/32490112449@sip.voip.united-telecom.be) State INIT going to sleep
  459. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_ROUTING (Cur 1 Tot 78)
  460. 2018-12-08 12:47:13.632025 [DEBUG] switch_channel.c:2249 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change DOWN -> RINGING
  461. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING
  462. 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:143 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA ROUTING
  463. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:236 sofia/default/32490112449@sip.voip.united-telecom.be Standard ROUTING
  464. 2018-12-08 12:47:13.632025 [INFO] mod_dialplan_xml.c:637 Processing 32490112449 <32490112449>->fwwwastpp in context default
  465. 2018-12-08 12:47:13.632025 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe684065e90 Connected.
  466. 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM system WHERE group_title IN ('global','opensips','callingcard')
  467. 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Dialed number : fwwwastpp
  468. 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Caller Id name / number  : 32490112449 / 32490112449
  469. 2018-12-08 12:47:13.632025 [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.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 ="fwwwastpp" LIMIT 1
  470. 2018-12-08 12:47:13.632025 [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="fwwwastpp" limit 1
  471. 2018-12-08 12:47:13.632025 [INFO] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call direction : outbound
  472. 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] [IPAUTHENTICATION] Query :SELECT ip_map.*, (SELECT number FROM accounts where id=accountid AND status=0 AND deleted=0) AS account_code FROM ip_map WHERE INET_ATON("87.238.166.8") BETWEEN(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) & 0xffffffff ^((0x1 <<(32 -  SUBSTRING_INDEX(`ip`, '/', -1))) -1 )) AND(INET_ATON(SUBSTRING_INDEX(`ip`, '/', 1)) |((0x100000000 >> SUBSTRING_INDEX(`ip`,'/', -1)) -1))  AND "fwwwastpp"  LIKE CONCAT(prefix,'%') ORDER BY LENGTH(prefix) DESC LIMIT 1
  473. 2018-12-08 12:47:13.632025 [NOTICE] switch_cpp.cpp:1365 [ASTPP] [Dialplan] Call authentication fail..!!1
  474. 2018-12-08 12:47:13.632025 [DEBUG] switch_cpp.cpp:1365 [ASTPP] Generated XML:
  475. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
  476. <document type="freeswitch/xml">
  477. <section name="dialplan" description="ASTPP Dialplan">
  478. <context name="default">
  479. <extension name="fwwwastpp">
  480. <condition field="destination_number" expression="fwwwastpp">
  481. <action application="log" data="WARNING Accountcode  is not authenticated!!"/>
  482. <action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav"/>
  483. <action application="set" data="original_caller_id_name=32490112449"/>
  484. <action application="set" data="original_caller_id_number=32490112449"/>
  485. <action application="set" data="error_cdr=1"/>
  486. <action application="set" data="callstart=2018-12-08 11:47:13"/>
  487. <action application="set" data="account_id=0"/>
  488. <action application="set" data="call_direction=outbound"/>
  489. <action application="set" data="sip_ignore_remote_cause=true"/>
  490. <action application="set" data="call_processed=internal"/>
  491. <action application="set" data="effective_destination_number=fwwwastpp"/>
  492. <action application="set" data="last_bridge_hangup_cause=AUTHENTICATION_FAIL"/>
  493. <action application="hangup" data="AUTHENTICATION_FAIL"/>
  494. </condition>
  495. </extension>
  496. </context>
  497. </section>
  498. </document>
  499. 2018-12-08 12:47:13.632025 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe684065e90 released.
  500. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be parsing [default->fwwwastpp] continue=false
  501. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Regex (PASS) [fwwwastpp] destination_number(fwwwastpp) =~ /fwwwastpp/ break=on-false
  502. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action log(WARNING Accountcode  is not authenticated!!)
  503. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  504. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_name=32490112449)
  505. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(original_caller_id_number=32490112449)
  506. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(error_cdr=1)
  507. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(callstart=2018-12-08 11:47:13)
  508. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(account_id=0)
  509. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_direction=outbound)
  510. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(sip_ignore_remote_cause=true)
  511. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(call_processed=internal)
  512. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(effective_destination_number=fwwwastpp)
  513. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  514. Dialplan: sofia/default/32490112449@sip.voip.united-telecom.be Action hangup(AUTHENTICATION_FAIL)
  515. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:286 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_ROUTING -> CS_EXECUTE
  516. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:643 (sofia/default/32490112449@sip.voip.united-telecom.be) State ROUTING going to sleep
  517. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_EXECUTE (Cur 1 Tot 78)
  518. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE
  519. 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:198 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA EXECUTE
  520. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_state_machine.c:328 sofia/default/32490112449@sip.voip.united-telecom.be Standard EXECUTE
  521. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be log(WARNING Accountcode  is not authenticated!!)
  522. 2018-12-08 12:47:13.632025 [WARNING] mod_dptools.c:1742 Accountcode  is not authenticated!!
  523. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_session.c:2647 Application playback Requires media! pre_answering channel sofia/default/32490112449@sip.voip.united-telecom.be
  524. 2018-12-08 12:47:13.632025 [INFO] switch_core_session.c:2649 Sending early media
  525. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/default/32490112449@sip.voip.united-telecom.be] 109.68.167.132 port 18056 -> 87.238.166.51 port 10548 codec: 8 ms: 20
  526. 2018-12-08 12:47:13.632025 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
  527. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7179 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf send payload to 101
  528. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7186 sofia/default/32490112449@sip.voip.united-telecom.be Set 2833 dtmf receive payload to 101
  529. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_media.c:7209 sofia/default/32490112449@sip.voip.united-telecom.be Set rtp dtmf delay to 40
  530. 2018-12-08 12:47:13.632025 [DEBUG] mod_sofia.c:2364 Ring SDP:
  531. v=0
  532. o=FreeSWITCH 1544251577 1544251578 IN IP4 109.68.167.132
  533. s=FreeSWITCH
  534. c=IN IP4 109.68.167.132
  535. t=0 0
  536. m=audio 18056 RTP/AVP 8 101
  537. a=rtpmap:8 PCMA/8000
  538. a=rtpmap:101 telephone-event/8000
  539. a=fmtp:101 0-16
  540. a=ptime:20
  541. a=sendrecv
  542.  
  543. 2018-12-08 12:47:13.632025 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/default/32490112449@sip.voip.united-telecom.be!
  544. 2018-12-08 12:47:13.632025 [DEBUG] switch_channel.c:3474 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change RINGING -> EARLY
  545. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be playback(/usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav)
  546. 2018-12-08 12:47:13.632025 [DEBUG] switch_core_file.c:342 File /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav sample rate 16000 doesn't match requested rate 8000
  547. 2018-12-08 12:47:13.632025 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
  548. 2018-12-08 12:47:13.632025 [DEBUG] sofia.c:7084 Channel sofia/default/32490112449@sip.voip.united-telecom.be entering state [early][183]
  549. 2018-12-08 12:47:13.692020 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
  550. 2018-12-08 12:47:15.152018 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp_expired.wav
  551. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_name=32490112449)
  552. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_name]=[32490112449]
  553. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(original_caller_id_number=32490112449)
  554. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [original_caller_id_number]=[32490112449]
  555. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(error_cdr=1)
  556. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [error_cdr]=[1]
  557. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(callstart=2018-12-08 11:47:13)
  558. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [callstart]=[2018-12-08 11:47:13]
  559. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(account_id=0)
  560. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [account_id]=[0]
  561. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_direction=outbound)
  562. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_direction]=[outbound]
  563. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(sip_ignore_remote_cause=true)
  564. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [sip_ignore_remote_cause]=[true]
  565. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(call_processed=internal)
  566. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [call_processed]=[internal]
  567. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(effective_destination_number=fwwwastpp)
  568. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [effective_destination_number]=[fwwwastpp]
  569. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be set(last_bridge_hangup_cause=AUTHENTICATION_FAIL)
  570. 2018-12-08 12:47:15.152018 [DEBUG] mod_dptools.c:1548 SET sofia/default/32490112449@sip.voip.united-telecom.be [last_bridge_hangup_cause]=[AUTHENTICATION_FAIL]
  571. EXECUTE sofia/default/32490112449@sip.voip.united-telecom.be hangup(AUTHENTICATION_FAIL)
  572. 2018-12-08 12:47:15.152018 [NOTICE] mod_dptools.c:1286 Hangup sofia/default/32490112449@sip.voip.united-telecom.be [CS_EXECUTE] [NORMAL_CLEARING]
  573. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_session.c:2815 sofia/default/32490112449@sip.voip.united-telecom.be skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  574. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:650 (sofia/default/32490112449@sip.voip.united-telecom.be) State EXECUTE going to sleep
  575. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_HANGUP (Cur 1 Tot 78)
  576. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:850 (sofia/default/32490112449@sip.voip.united-telecom.be) Callstate Change EARLY -> HANGUP
  577. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP
  578. 2018-12-08 12:47:15.152018 [DEBUG] mod_sofia.c:438 Channel sofia/default/32490112449@sip.voip.united-telecom.be hanging up, cause: NORMAL_CLEARING
  579. 2018-12-08 12:47:15.152018 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
  580. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:60 sofia/default/32490112449@sip.voip.united-telecom.be Standard HANGUP, cause: NORMAL_CLEARING
  581. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:852 (sofia/default/32490112449@sip.voip.united-telecom.be) State HANGUP going to sleep
  582. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:619 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_HANGUP -> CS_REPORTING
  583. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:584 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_REPORTING (Cur 1 Tot 78)
  584. 2018-12-08 12:47:15.152018 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING
  585. 2018-12-08 12:47:15.152018 [INFO] mod_json_cdr.c:271 Process [0f9c6130-675c-49f6-a7c9-fb7491b5482f.cdr.json]
  586. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:174 sofia/default/32490112449@sip.voip.united-telecom.be Standard REPORTING, cause: NORMAL_CLEARING
  587. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:938 (sofia/default/32490112449@sip.voip.united-telecom.be) State REPORTING going to sleep
  588. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:610 (sofia/default/32490112449@sip.voip.united-telecom.be) State Change CS_REPORTING -> CS_DESTROY
  589. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_session.c:1665 Session 78 (sofia/default/32490112449@sip.voip.united-telecom.be) Locked, Waiting on external entities
  590. 2018-12-08 12:47:15.172004 [NOTICE] switch_core_session.c:1683 Session 78 (sofia/default/32490112449@sip.voip.united-telecom.be) Ended
  591. 2018-12-08 12:47:15.172004 [NOTICE] switch_core_session.c:1687 Close Channel sofia/default/32490112449@sip.voip.united-telecom.be [CS_DESTROY]
  592. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:741 (sofia/default/32490112449@sip.voip.united-telecom.be) Running State Change CS_DESTROY (Cur 0 Tot 78)
  593. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY
  594. 2018-12-08 12:47:15.172004 [DEBUG] mod_sofia.c:343 sofia/default/32490112449@sip.voip.united-telecom.be SOFIA DESTROY
  595. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:181 sofia/default/32490112449@sip.voip.united-telecom.be Standard DESTROY
  596. 2018-12-08 12:47:15.172004 [DEBUG] switch_core_state_machine.c:751 (sofia/default/32490112449@sip.voip.united-telecom.be) State DESTROY going to sleep

Reply to "Untitled"