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