1.  
2. 2024-12-16 13:10:33.295285 98.00% [NOTICE] switch_channel.c:1142 New Channel sofia/default/1234@00133897.yoursipcloud.com [cef11b45-f79f-4ae3-98d8-7e10f9a444c4]
3. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_NEW (Cur 17 Tot 1667)
4. 2024-12-16 13:10:33.295285 98.00% [INFO] sofia.c:10459 sofia/default/1234@00133897.yoursipcloud.com receiving invite from 185.249.40.236:5060 version: 1.10.10-release  64bit call-id: DLGCH_KB4dHz46HGp6Py4EHCYbJygdLQoqGH59
5. 2024-12-16 13:10:33.295285 98.00% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 185.249.40.236:0.
6. 2024-12-16 13:10:33.295285 98.00% [DEBUG] sofia.c:10582 IP 185.249.40.236 Approved by acl "default[]". Access Granted.
7. 2024-12-16 13:10:33.295285 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/1234@00133897.yoursipcloud.com entering state [received][100]
8. 2024-12-16 13:10:33.295285 98.00% [DEBUG] sofia.c:7503 Remote SDP:
9. v=0
10. o=Z 0 0 IN IP4 103.240.35.46
11. s=Z
12. c=IN IP4 103.240.35.46
13. t=0 0
14. m=audio 8000 RTP/AVP 3 110 8 0 97 101
15. a=rtpmap:110 speex/8000
16. a=rtpmap:97 iLBC/8000
17. a=fmtp:97 mode=30
18. a=rtpmap:101 telephone-event/8000
19. a=fmtp:101 0-16
20.  
21. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
22. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
23. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
24. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
25. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
26. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[G729:18:8000:20:8000:1]
27. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
28. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
29. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
30. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
31. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
32. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
33. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
34. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
35. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
36. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:97:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
37. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:97:8000:30:0:1]/[G729:18:8000:20:8000:1]
38. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
39. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/1234@00133897.yoursipcloud.com PCMA/8000 20 ms 160 samples 64000 bits 1 channels
40. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_codec.c:111 sofia/default/1234@00133897.yoursipcloud.com Original read codec set to PCMA:8
41. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
42. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_media.c:5856 sofia/default/1234@00133897.yoursipcloud.com Set 2833 dtmf send payload to 101 recv payload to 101
43. 2024-12-16 13:10:33.295285 98.00% [DEBUG] sofia.c:7927 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_NEW -> CS_INIT
44. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:600 (sofia/default/1234@00133897.yoursipcloud.com) State NEW
45. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_INIT (Cur 17 Tot 1667)
46. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1234@00133897.yoursipcloud.com) State INIT
47. 2024-12-16 13:10:33.295285 98.00% [DEBUG] mod_sofia.c:97 sofia/default/1234@00133897.yoursipcloud.com SOFIA INIT
48. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:40 sofia/default/1234@00133897.yoursipcloud.com Standard INIT
49. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_INIT -> CS_ROUTING
50. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1234@00133897.yoursipcloud.com) State INIT going to sleep
51. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_ROUTING (Cur 17 Tot 1667)
52. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_channel.c:2399 (sofia/default/1234@00133897.yoursipcloud.com) Callstate Change DOWN -> RINGING
53. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1234@00133897.yoursipcloud.com) State ROUTING
54. 2024-12-16 13:10:33.295285 98.00% [DEBUG] mod_sofia.c:158 sofia/default/1234@00133897.yoursipcloud.com SOFIA ROUTING
55. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_core_state_machine.c:230 sofia/default/1234@00133897.yoursipcloud.com Standard ROUTING
56. 2024-12-16 13:10:33.295285 98.00% [INFO] mod_dialplan_xml.c:639 Processing 1234 <1234>->31855004076 in context default
57. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
58. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
59. 2024-12-16 13:10:33.295285 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
60. 2024-12-16 13:10:33.295285 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 31855004076
61. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '31855004076' limit 1
62. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '31855004076' AND status=0 limit 1
63. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '31855004076' and status=0 LIMIT 1
64. 2024-12-16 13:10:33.295285 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : TEST / 318002666436
65. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
66. 2024-12-16 13:10:33.295285 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
67. 2024-12-16 13:10:33.295285 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 00133897]
68. 2024-12-16 13:10:33.295285 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "00133897" AND deleted = 0 limit 1
69. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="31855004076" AND (accountid = 0 OR status = 1) LIMIT 1
70. 2024-12-16 13:10:33.315277 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
71. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
72. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
73. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 1884
74. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^31855004076.*' OR patterns = '^3185500407.*' OR patterns = '^318550040.*' OR patterns = '^31855004.*' OR patterns = '^3185500.*' OR patterns = '^318550.*' OR patterns = '^31855.*' OR patterns = '^3185.*' OR patterns = '^318.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1884 ORDER BY LENGTH(PKGPTR.patterns) DESC
75. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="31855004076" AND A.accountid = '1884' limit 1
76. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 31855004076
77. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
78. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
79. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '31855004076' OR number = '3185500407' OR number = '318550040' OR number = '31855004' OR number = '3185500' OR number = '318550' OR number = '31855' OR number = '3185' OR number = '318' OR number = '31' OR number = '3' OR number ='--') OR (number = '31855004076*' OR number = '3185500407*' OR number = '318550040*' OR number = '31855004*' OR number = '3185500*' OR number = '318550*' OR number = '31855*' OR number = '3185*' OR number = '318*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '318002666436' OR number = '31800266643' OR number = '3180026664' OR number = '318002666' OR number = '31800266' OR number = '3180026' OR number = '318002' OR number = '31800' OR number = '3180' OR number = '318' OR number = '31' OR number = '3' OR number ='--') OR (number = '318002666436*' OR number = '31800266643*' OR number = '3180026664*' OR number = '318002666*' OR number = '31800266*' OR number = '3180026*' OR number = '318002*' OR number = '31800*' OR number = '3180*' OR number = '318*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
80. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
81. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '31855004076' OR routing_prefix = '3185500407' OR routing_prefix = '318550040' OR routing_prefix = '31855004' OR routing_prefix = '3185500' OR routing_prefix = '318550' OR routing_prefix = '31855' OR routing_prefix = '3185' OR routing_prefix = '318' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
82. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
83. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 1884
84. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 00133897
85. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10
86. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
87. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 6
88. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
89. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
90. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
91. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
92. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
93. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
94. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
95. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 183
96. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
97. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
98. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
99. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 31855004076
100. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 136
101. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 1884
102. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
103. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10 balance Accountid 1884 !!!
104. 2024-12-16 13:10:33.315277 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
105. 2024-12-16 13:10:33.315277 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] FINDING LIMIT FOR RESELLER: 12
106. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
107. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Reseller Information ===================
108. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 12
109. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 3847299300
110. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 99300.97566
111. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
112. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 44
113. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
114. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
115. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
116. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
117. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
118. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 183
119. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
120. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
121. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
122. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 31855004076
123. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 136
124. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 12
125. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
126. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your99300.97566 balance Accountid 12 !!!
127. 2024-12-16 13:10:33.315277 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
128. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Reseller xml_user_rates : ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884||ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884
129. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
130. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Lossless Routing Status : 0
131. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
132. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
133. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
134. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1884 AND status=0 LIMIT 1
135. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1884" AND deleted = 0 limit 1
136. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:1884
137. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:1884
138. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 1884
139. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1884 limit 1
140. 2024-12-16 13:10:33.315277 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : 00133897.yoursipcloud.com
141. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition Id : 107
142. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '107' and status =0 LIMIT 1
143. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Failover Timecondition : [{"extensions":"#","no_answer_call_type":"12"}]
144. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition : [{"hour":"00-23","minute":"00-59","wday":"1-7","mday":"1-31","mon":"1-12","year":"2024-2024","extensions":"377","no_answer_call_type":"7","priority":"1"}]
145. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Priority : 1
146. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from timezone WHERE id = '277' LIMIT 1
147. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str :  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2024-2024"
148. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
149. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
150. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
151. 2024-12-16 13:10:33.315277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
152. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "107" AND accounts.status = 0
153. 2024-12-16 13:10:33.315277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1884 limit 1
154. 2024-12-16 13:10:33.335279 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : 00133897.yoursipcloud.com
155. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 1884  and status = 0 limit 1
156. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_11
157. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] TimeCondition Id : 107
158. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '107' and status =0 LIMIT 1
159. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
160. <document type="freeswitch/xml">
161. <section name="dialplan" description="ASTPP Dialplan">
162. <context name="default">
163. <extension name="+31 6 26673579" continue="true">
164. <condition break="never">
165. <action application="set" data="timezone=Europe/Amsterdam" inline="true"/>
166. </condition>
167. <condition  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2024-2024"  break="never">
168. <action application="set" data="office_status=open" inline="true"/>
169. <action application="set" data="extensions=377" inline="true"/>
170. <action application="set" data="no_answer_call_type=7" inline="true"/>
171. </condition>
172. </extension>
173. <extension name="tod route, x31855004076">
174. <condition field="destination_number" expression="^(\+?)?(31855004076)$">
175. <action application="execute_extension" data="31855004076_${office_status}"/>
176. </condition>
177. </extension>
178. <extension name="office is open">
179. <condition field="destination_number" expression="^(\+?)?(31855004076_open)$">
180. <action application="set" data="domain_name=00133897.yoursipcloud.com"/>
181. <action application="set" data="set_extensions=${extensions}"/>
182. <action application="set" data="no_answer_flag=true"/>
183. <action application="set" data="no_answer_call_type=${no_answer_call_type}"/>
184. <action application="set" data="no_answer_number=${extensions}" />
185. <action application="transfer" data="1884 XML default"/>
186. </condition>
187. </extension>
188. <extension name="31855004076">
189. <condition field="destination_number" expression="31855004076">
190. <action application="set" data="effective_destination_number=31855004076"/>
191. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
192. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
193. <action application="set" data="callstart=2024-12-16 13:10:33"/>
194. <action application="set" data="hangup_after_bridge=true"/>
195. <action application="set" data="continue_on_fail=TRUE"/>
196. <action application="set" data="account_id=1884"/>
197. <action application="set" data="parent_id=12"/>
198. <action application="set" data="entity_id=0"/>
199. <action application="set" data="call_processed=internal"/>
200. <action application="set" data="call_direction=inbound"/>
201. <action application="set" data="accountname=default"/>
202. <action application="set" data="filename1=astpp.pbx.lua"/>
203. <action application="set" data="origination_rates_did=ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884||ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884"/>
204. <action application="set" data="origination_rates=0"/>
205. <action application="set" data="original_caller_id_name=TEST"/>
206. <action application="set" data="original_caller_id_number=318002666436"/>
207. <action application="limit" data="db 00133897 user_00133897 10 "/>
208. <action application="limit" data="hash CPS_00133897 CPS_user_00133897 1/1 "/>
209. <action application="set" data="effective_caller_id_name=TEST"/>
210. <action application="set" data="effective_caller_id_number=318002666436"/>
211. <action application="set" data="receiver_accid=1884"/>
212. <action application="export" data="domain_name=00133897.yoursipcloud.com"/>
213. <action application="export" data="sip_to_host=00133897.yoursipcloud.com"/>
214. <action application="export" data="presence_data=x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250"/>
215. <action application="export" data="call_type=11"/>
216. </condition>
217. </extension>
218. </context>
219. </section>
220. </document>
221. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->+31 6 26673579] continue=true
222. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Absolute Condition [+31 6 26673579]
223. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(timezone=Europe/Amsterdam) INLINE
224. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(timezone=Europe/Amsterdam)
225. 2024-12-16 13:10:33.335279 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [timezone]=[Europe/Amsterdam]
226. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Date/Time Match (PASS) [+31 6 26673579] break=never
227. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(office_status=open) INLINE
228. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(office_status=open)
229. 2024-12-16 13:10:33.335279 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [office_status]=[open]
230. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(extensions=377) INLINE
231. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(extensions=377)
232. 2024-12-16 13:10:33.335279 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [extensions]=[377]
233. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_call_type=7) INLINE
234. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_call_type=7)
235. 2024-12-16 13:10:33.335279 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_call_type]=[7]
236. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->tod route, x31855004076] continue=false
237. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Regex (PASS) [tod route, x31855004076] destination_number(31855004076) =~ /^(\+?)?(31855004076)$/ break=on-false
238. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action execute_extension(31855004076_${office_status})
239. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_core_state_machine.c:281 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
240. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1234@00133897.yoursipcloud.com) State ROUTING going to sleep
241. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 17 Tot 1667)
242. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1234@00133897.yoursipcloud.com) State EXECUTE
243. 2024-12-16 13:10:33.335279 98.00% [DEBUG] mod_sofia.c:213 sofia/default/1234@00133897.yoursipcloud.com SOFIA EXECUTE
244. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_core_state_machine.c:323 sofia/default/1234@00133897.yoursipcloud.com Standard EXECUTE
245. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com execute_extension(31855004076_open)
246. 2024-12-16 13:10:33.335279 98.00% [INFO] mod_dialplan_xml.c:639 Processing 1234 <1234>->31855004076_open in context default
247. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
248. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
249. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
250. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 31855004076
251. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '31855004076' limit 1
252. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '31855004076' AND status=0 limit 1
253. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '31855004076' and status=0 LIMIT 1
254. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : TEST / 318002666436
255. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
256. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
257. 2024-12-16 13:10:33.335279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 00133897]
258. 2024-12-16 13:10:33.335279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "00133897" AND deleted = 0 limit 1
259. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="31855004076" AND (accountid = 0 OR status = 1) LIMIT 1
260. 2024-12-16 13:10:33.355279 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
261. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
262. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
263. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 1884
264. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^31855004076.*' OR patterns = '^3185500407.*' OR patterns = '^318550040.*' OR patterns = '^31855004.*' OR patterns = '^3185500.*' OR patterns = '^318550.*' OR patterns = '^31855.*' OR patterns = '^3185.*' OR patterns = '^318.*' OR patterns = '^31.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 1884 ORDER BY LENGTH(PKGPTR.patterns) DESC
265. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="31855004076" AND A.accountid = '1884' limit 1
266. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 31855004076
267. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
268. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
269. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '31855004076' OR number = '3185500407' OR number = '318550040' OR number = '31855004' OR number = '3185500' OR number = '318550' OR number = '31855' OR number = '3185' OR number = '318' OR number = '31' OR number = '3' OR number ='--') OR (number = '31855004076*' OR number = '3185500407*' OR number = '318550040*' OR number = '31855004*' OR number = '3185500*' OR number = '318550*' OR number = '31855*' OR number = '3185*' OR number = '318*' OR number = '31*' OR number = '3*' OR number ='--') OR (number = '318002666436' OR number = '31800266643' OR number = '3180026664' OR number = '318002666' OR number = '31800266' OR number = '3180026' OR number = '318002' OR number = '31800' OR number = '3180' OR number = '318' OR number = '31' OR number = '3' OR number ='--') OR (number = '318002666436*' OR number = '31800266643*' OR number = '3180026664*' OR number = '318002666*' OR number = '31800266*' OR number = '3180026*' OR number = '318002*' OR number = '31800*' OR number = '3180*' OR number = '318*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
270. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
271. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '31855004076' OR routing_prefix = '3185500407' OR routing_prefix = '318550040' OR routing_prefix = '31855004' OR routing_prefix = '3185500' OR routing_prefix = '318550' OR routing_prefix = '31855' OR routing_prefix = '3185' OR routing_prefix = '318' OR routing_prefix = '31' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
272. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
273. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 1884
274. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 00133897
275. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10
276. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
277. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 6
278. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
279. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
280. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
281. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
282. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
283. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
284. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
285. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 183
286. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
287. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
288. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
289. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 31855004076
290. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 136
291. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 1884
292. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
293. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10 balance Accountid 1884 !!!
294. 2024-12-16 13:10:33.355279 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
295. 2024-12-16 13:10:33.355279 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] FINDING LIMIT FOR RESELLER: 12
296. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "12" AND deleted = 0 limit 1
297. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Reseller Information ===================
298. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 12
299. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 3847299300
300. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 99300.97566
301. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
302. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 44
303. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 44 AND status = 0
304. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
305. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="31855004076" LIMIT 1
306. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
307. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
308. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 183
309. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
310. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
311. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
312. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 31855004076
313. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 136
314. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 12
315. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
316. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your99300.97566 balance Accountid 12 !!!
317. 2024-12-16 13:10:33.355279 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
318. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Reseller xml_user_rates : ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884||ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884
319. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
320. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Lossless Routing Status : 0
321. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
322. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
323. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
324. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 1884 AND status=0 LIMIT 1
325. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1884" AND deleted = 0 limit 1
326. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:1884
327. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:1884
328. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 1884
329. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1884 limit 1
330. 2024-12-16 13:10:33.355279 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : 00133897.yoursipcloud.com
331. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition Id : 107
332. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '107' and status =0 LIMIT 1
333. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Failover Timecondition : [{"extensions":"#","no_answer_call_type":"12"}]
334. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition : [{"hour":"00-23","minute":"00-59","wday":"1-7","mday":"1-31","mon":"1-12","year":"2024-2024","extensions":"377","no_answer_call_type":"7","priority":"1"}]
335. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Priority : 1
336. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from timezone WHERE id = '277' LIMIT 1
337. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str :  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2024-2024"
338. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
339. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
340. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
341. 2024-12-16 13:10:33.355279 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
342. 2024-12-16 13:10:33.355279 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET get_record_info] Query :SELECT sip_device_routing.is_recording FROM sip_devices AS sip_devices, accounts AS accounts ,sip_device_routing AS sip_device_routing  WHERE sip_devices.accountid = accounts.id AND sip_devices.id = sip_device_routing.sip_device_id AND sip_devices.username = "107" AND accounts.status = 0
343. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1884 limit 1
344. 2024-12-16 13:10:33.375277 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : 00133897.yoursipcloud.com
345. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 1884  and status = 0 limit 1
346. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_11
347. 2024-12-16 13:10:33.375277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] TimeCondition Id : 107
348. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_TIME_CONDITION_QUERY] Query :SELECT * from time_condition WHERE id = '107' and status =0 LIMIT 1
349. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
350. <document type="freeswitch/xml">
351. <section name="dialplan" description="ASTPP Dialplan">
352. <context name="default">
353. <extension name="+31 6 26673579" continue="true">
354. <condition break="never">
355. <action application="set" data="timezone=Europe/Amsterdam" inline="true"/>
356. </condition>
357. <condition  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2024-2024"  break="never">
358. <action application="set" data="office_status=open" inline="true"/>
359. <action application="set" data="extensions=377" inline="true"/>
360. <action application="set" data="no_answer_call_type=7" inline="true"/>
361. </condition>
362. </extension>
363. <extension name="tod route, x31855004076">
364. <condition field="destination_number" expression="^(\+?)?(31855004076)$">
365. <action application="execute_extension" data="31855004076_${office_status}"/>
366. </condition>
367. </extension>
368. <extension name="office is open">
369. <condition field="destination_number" expression="^(\+?)?(31855004076_open)$">
370. <action application="set" data="domain_name=00133897.yoursipcloud.com"/>
371. <action application="set" data="set_extensions=${extensions}"/>
372. <action application="set" data="no_answer_flag=true"/>
373. <action application="set" data="no_answer_call_type=${no_answer_call_type}"/>
374. <action application="set" data="no_answer_number=${extensions}" />
375. <action application="transfer" data="1884 XML default"/>
376. </condition>
377. </extension>
378. <extension name="31855004076">
379. <condition field="destination_number" expression="31855004076">
380. <action application="set" data="effective_destination_number=31855004076"/>
381. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
382. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
383. <action application="set" data="callstart=2024-12-16 13:10:33"/>
384. <action application="set" data="hangup_after_bridge=true"/>
385. <action application="set" data="continue_on_fail=TRUE"/>
386. <action application="set" data="account_id=1884"/>
387. <action application="set" data="parent_id=12"/>
388. <action application="set" data="entity_id=0"/>
389. <action application="set" data="call_processed=internal"/>
390. <action application="set" data="call_direction=inbound"/>
391. <action application="set" data="accountname=default"/>
392. <action application="set" data="filename1=astpp.pbx.lua"/>
393. <action application="set" data="origination_rates_did=ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884||ID:183|CODE:^31855004076.*|DESTINATION:31855004076|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:11|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:136|ACCID:1884"/>
394. <action application="set" data="origination_rates=0"/>
395. <action application="set" data="original_caller_id_name=TEST"/>
396. <action application="set" data="original_caller_id_number=318002666436"/>
397. <action application="limit" data="db 00133897 user_00133897 10 "/>
398. <action application="limit" data="hash CPS_00133897 CPS_user_00133897 1/1 "/>
399. <action application="set" data="effective_caller_id_name=TEST"/>
400. <action application="set" data="effective_caller_id_number=318002666436"/>
401. <action application="set" data="receiver_accid=1884"/>
402. <action application="export" data="domain_name=00133897.yoursipcloud.com"/>
403. <action application="export" data="sip_to_host=00133897.yoursipcloud.com"/>
404. <action application="export" data="presence_data=x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250"/>
405. <action application="export" data="call_type=11"/>
406. </condition>
407. </extension>
408. </context>
409. </section>
410. </document>
411. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->+31 6 26673579] continue=true
412. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Absolute Condition [+31 6 26673579]
413. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(timezone=Europe/Amsterdam) INLINE
414. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(timezone=Europe/Amsterdam)
415. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [timezone]=[Europe/Amsterdam]
416. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Date/Time Match (PASS) [+31 6 26673579] break=never
417. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(office_status=open) INLINE
418. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(office_status=open)
419. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [office_status]=[open]
420. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(extensions=377) INLINE
421. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(extensions=377)
422. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [extensions]=[377]
423. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_call_type=7) INLINE
424. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_call_type=7)
425. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_call_type]=[7]
426. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->tod route, x31855004076] continue=false
427. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Regex (FAIL) [tod route, x31855004076] destination_number(31855004076_open) =~ /^(\+?)?(31855004076)$/ break=on-false
428. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->office is open] continue=false
429. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Regex (PASS) [office is open] destination_number(31855004076_open) =~ /^(\+?)?(31855004076_open)$/ break=on-false
430. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(domain_name=00133897.yoursipcloud.com)
431. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(set_extensions=${extensions})
432. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_flag=true)
433. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_call_type=${no_answer_call_type})
434. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_number=${extensions})
435. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action transfer(1884 XML default)
436. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] set(domain_name=00133897.yoursipcloud.com)
437. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(domain_name=00133897.yoursipcloud.com)
438. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [domain_name]=[00133897.yoursipcloud.com]
439. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] set(set_extensions=${extensions})
440. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(set_extensions=377)
441. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [set_extensions]=[377]
442. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] set(no_answer_flag=true)
443. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_flag=true)
444. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_flag]=[true]
445. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] set(no_answer_call_type=${no_answer_call_type})
446. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_call_type=7)
447. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_call_type]=[7]
448. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] set(no_answer_number=${extensions})
449. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_number=377)
450. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_number]=[377]
451. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_core_session.c:3089 Execute [depth=1] transfer(1884 XML default)
452. EXECUTE [depth=1] sofia/default/1234@00133897.yoursipcloud.com transfer(1884 XML default)
453. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_ivr.c:2288 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_EXECUTE -> CS_ROUTING
454. 2024-12-16 13:10:33.375277 98.00% [NOTICE] switch_ivr.c:2295 Transfer sofia/default/1234@00133897.yoursipcloud.com to XML[1884@default]
455. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1234@00133897.yoursipcloud.com) State EXECUTE going to sleep
456. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_ROUTING (Cur 17 Tot 1667)
457. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1234@00133897.yoursipcloud.com) State ROUTING
458. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
459. 2024-12-16 13:10:33.375277 98.00% [DEBUG] mod_sofia.c:158 sofia/default/1234@00133897.yoursipcloud.com SOFIA ROUTING
460. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_core_state_machine.c:230 sofia/default/1234@00133897.yoursipcloud.com Standard ROUTING
461. 2024-12-16 13:10:33.375277 98.00% [INFO] mod_dialplan_xml.c:639 Processing 1234 <1234>->1884 in context default
462. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
463. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
464. 2024-12-16 13:10:33.375277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
465. 2024-12-16 13:10:33.375277 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 1884
466. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '1884' limit 1
467. 2024-12-16 13:10:33.375277 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '1884' AND status=0 limit 1
468. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '1884' and status=0 LIMIT 1
469. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : TEST / 318002666436
470. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="1884" LIMIT 1
471. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1884"  AND domains.domain="00133897.yoursipcloud.com" limit 1
472. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
473. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 00133897]
474. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "00133897" AND deleted = 0 limit 1
475. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^1884.*' OR blocked_patterns = '^188.*' OR blocked_patterns = '^18.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 1884 limit 1
476. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="1884" AND (accountid = 0 OR status = 1) LIMIT 1
477. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "1884" AND deleted = 0 limit 1
478. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^1884.*' OR blocked_patterns = '^188.*' OR blocked_patterns = '^18.*' OR blocked_patterns = '^1.*' OR blocked_patterns ='--') AND accountid = 1884 limit 1
479. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated RingGroup No Answer Forward :1884
480. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated RingGroup No Answer Forward2 :1884
481. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] select accountid from domains where domain = '00133897.yoursipcloud.com'
482. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Accountid 1884
483. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] select number,first_name from accounts where id = '1884'
484. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 1884 limit 1
485. 2024-12-16 13:10:33.395278 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [ringgroup_no_answer_forward] User Domain00133897.yoursipcloud.com
486. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated RingGroup No Answer Forward3 :1884
487. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [DID_PBX_INFO]Userinfo ID  :1884
488. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : 7
489. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : Ring Group
490. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET RING GROUP INFO] Query :SELECT id as ringgroup_id,name as ringgroup_name,strategy as ringgroup_strategy,destinations as ringgroup_destinations,description as ringgroup_description,announcementid as ringgroup_announcementid,call_record as ringgroup_call_record,skip_busy as ringgroup_skip_busy,ringbackid as ringgroup_ringbackid,calleridname as ringgroup_calleridname,calleridnumber as ringgroup_calleridnumber,no_answer as ringgroup_no_answer,no_answer_call_type as no_answer_call_type,no_answer_call_type_value from pbx_ringgroup WHERE id = 377 and status = 0 LIMIT 1
491. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Final Count : 0===
492. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 1884  and status = 0 limit 1
493. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Recording Flag : 1
494. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1884
495. 2024-12-16 13:10:33.395278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Dlr Str : [sip_invite_params=user=Forwarded-PSTN,sip_h_p-call_type='custom_forward',sip_h_P-RGPSTnAcctCode=00133897,call_timeout=30,confirm=false,announcement=no_file,dialed_user=31626673579#,leg_timeout=30,leg_delay_start=0]sofia/${sofia_profile_name}/31626673579@00133897.yoursipcloud.com:5060|
496. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '315' LIMIT 1
497. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
498. 2024-12-16 13:10:33.395278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
499. 2024-12-16 13:10:33.415278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Userinfo2 :1884
500. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
501. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
502. <document type="freeswitch/xml">
503. <section name="dialplan" description="ASTPP Dialplan">
504. <context name="default">
505. <extension name="1884">
506. <condition field="destination_number" expression="1884">
507. <action application="export" data="presence_data=x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250"/>
508. <action application="export" data="did_user_domain=00133897.yoursipcloud.com"/>
509. <action application="set" data="continue_on_fail=TRUE"/>
510. <action application="set" data="ignore_early_media=true"/>
511. <action application="set" data="no_answer_flag=false"/>
512. <action application="set" data="did_calltype=7"/>
513. <action application="set" data="did_extensions=377"/>
514. <action application="set" data="sip_h_P-ring_timeout=30"/>
515. <action application="set" data="group_confirm_key=exec"/>
516. <action application="set" data="group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua"/>
517. <action application="set" data="callstart=2024-12-16 13:10:33"/>
518. <action application="set" data="account_id=1884"/>
519. <action application="set" data="effective_caller_id_name=TEST"/>
520. <action application="set" data="effective_caller_id_number=318002666436"/>
521. <action application="set" data="original_caller_id_name=TEST"/>
522. <action application="set" data="original_caller_id_number=318002666436"/>
523. <action application="set" data="parent_id=12"/>
524. <action application="set" data="effective_destination_number=31855004076"/>
525. <action application="set" data="ringback=/var/www/html/astpp//upload/pbx/meldtekst_1_nieuweversiesept2013_.wav"/>
526. <action application="set" data="module_name=pbx"/>
527. <action application="db" data="insert/pickup_any_group_${callgroup}/377/${uuid}"/>
528. <action application="set" data="dialed_extension=transfer_call"/>
529. <action application="export" data="dialed_extension=transfer_call"/>
530. <action application="bind_meta_app" data="2 b s execute_extension::transfer_call_2 XML default"/>
531. <action application="bind_meta_app" data="3 b s execute_extension::transfer_call_3 XML default"/>
532. <action application="export" data="ring_group_transfer=true"/>
533. <action application="bridge" data="{sip_h_P-call_type='custom_forward',ignore_early_media=true}[sip_invite_params=user=Forwarded-PSTN,sip_h_p-call_type='custom_forward',sip_h_P-RGPSTnAcctCode=00133897,call_timeout=30,confirm=false,announcement=no_file,dialed_user=31626673579#,leg_timeout=30,leg_delay_start=0]sofia/${sofia_profile_name}/31626673579@00133897.yoursipcloud.com:5060|"/>
534. <action application="set" data="original_did_number=1884"/>
535. </condition>
536. </extension>
537. </context>
538. </section>
539. </document>
540. Dialplan: sofia/default/1234@00133897.yoursipcloud.com parsing [default->1884] continue=false
541. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Regex (PASS) [1884] destination_number(1884) =~ /1884/ break=on-false
542. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action export(presence_data=x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250)
543. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action export(did_user_domain=00133897.yoursipcloud.com)
544. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(continue_on_fail=TRUE)
545. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(ignore_early_media=true)
546. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(no_answer_flag=false)
547. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(did_calltype=7)
548. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(did_extensions=377)
549. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(sip_h_P-ring_timeout=30)
550. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(group_confirm_key=exec)
551. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
552. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(callstart=2024-12-16 13:10:33)
553. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(account_id=1884)
554. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(effective_caller_id_name=TEST)
555. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(effective_caller_id_number=318002666436)
556. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(original_caller_id_name=TEST)
557. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(original_caller_id_number=318002666436)
558. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(parent_id=12)
559. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(effective_destination_number=31855004076)
560. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(ringback=/var/www/html/astpp//upload/pbx/meldtekst_1_nieuweversiesept2013_.wav)
561. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(module_name=pbx)
562. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action db(insert/pickup_any_group_${callgroup}/377/${uuid})
563. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(dialed_extension=transfer_call)
564. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action export(dialed_extension=transfer_call)
565. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
566. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
567. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action export(ring_group_transfer=true)
568. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action bridge({sip_h_P-call_type='custom_forward',ignore_early_media=true}[sip_invite_params=user=Forwarded-PSTN,sip_h_p-call_type='custom_forward',sip_h_P-RGPSTnAcctCode=00133897,call_timeout=30,confirm=false,announcement=no_file,dialed_user=31626673579#,leg_timeout=30,leg_delay_start=0]sofia/${sofia_profile_name}/31626673579@00133897.yoursipcloud.com:5060|)
569. Dialplan: sofia/default/1234@00133897.yoursipcloud.com Action set(original_did_number=1884)
570. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:281 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_ROUTING -> CS_EXECUTE
571. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1234@00133897.yoursipcloud.com) State ROUTING going to sleep
572. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_EXECUTE (Cur 17 Tot 1667)
573. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1234@00133897.yoursipcloud.com) State EXECUTE
574. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_sofia.c:213 sofia/default/1234@00133897.yoursipcloud.com SOFIA EXECUTE
575. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:323 sofia/default/1234@00133897.yoursipcloud.com Standard EXECUTE
576. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com export(presence_data=x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250)
577. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250]
578. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com export(did_user_domain=00133897.yoursipcloud.com)
579. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [did_user_domain]=[00133897.yoursipcloud.com]
580. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(continue_on_fail=TRUE)
581. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [continue_on_fail]=[TRUE]
582. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(ignore_early_media=true)
583. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [ignore_early_media]=[true]
584. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(no_answer_flag=false)
585. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [no_answer_flag]=[false]
586. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(did_calltype=7)
587. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [did_calltype]=[7]
588. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(did_extensions=377)
589. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [did_extensions]=[377]
590. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(sip_h_P-ring_timeout=30)
591. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [sip_h_P-ring_timeout]=[30]
592. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(group_confirm_key=exec)
593. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [group_confirm_key]=[exec]
594. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(group_confirm_file=lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
595. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [group_confirm_file]=[lua /usr/share/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua]
596. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(callstart=2024-12-16 13:10:33)
597. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [callstart]=[2024-12-16 13:10:33]
598. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(account_id=1884)
599. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [account_id]=[1884]
600. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(effective_caller_id_name=TEST)
601. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [effective_caller_id_name]=[TEST]
602. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(effective_caller_id_number=318002666436)
603. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [effective_caller_id_number]=[318002666436]
604. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(original_caller_id_name=TEST)
605. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [original_caller_id_name]=[TEST]
606. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(original_caller_id_number=318002666436)
607. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [original_caller_id_number]=[318002666436]
608. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(parent_id=12)
609. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [parent_id]=[12]
610. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(effective_destination_number=31855004076)
611. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [effective_destination_number]=[31855004076]
612. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(ringback=/var/www/html/astpp//upload/pbx/meldtekst_1_nieuweversiesept2013_.wav)
613. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [ringback]=[/var/www/html/astpp//upload/pbx/meldtekst_1_nieuweversiesept2013_.wav]
614. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(module_name=pbx)
615. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [module_name]=[pbx]
616. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com db(insert/pickup_any_group_/377/cef11b45-f79f-4ae3-98d8-7e10f9a444c4)
617. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(dialed_extension=transfer_call)
618. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [dialed_extension]=[transfer_call]
619. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com export(dialed_extension=transfer_call)
620. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [dialed_extension]=[transfer_call]
621. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
622. 2024-12-16 13:10:33.415278 98.00% [INFO] switch_ivr_async.c:4782 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
623. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
624. 2024-12-16 13:10:33.415278 98.00% [INFO] switch_ivr_async.c:4782 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
625. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com export(ring_group_transfer=true)
626. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [ring_group_transfer]=[true]
627. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com bridge({sip_h_P-call_type='custom_forward',ignore_early_media=true}[sip_invite_params=user=Forwarded-PSTN,sip_h_p-call_type='custom_forward',sip_h_P-RGPSTnAcctCode=00133897,call_timeout=30,confirm=false,announcement=no_file,dialed_user=31626673579#,leg_timeout=30,leg_delay_start=0]sofia/default/31626673579@00133897.yoursipcloud.com:5060|)
628. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1288 sofia/default/1234@00133897.yoursipcloud.com EXPORTING[export_vars] [presence_data]=[x|||Crediteuren(00133897)|||||||||DID|||111.223.26.250] to event
629. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1288 sofia/default/1234@00133897.yoursipcloud.com EXPORTING[export_vars] [did_user_domain]=[00133897.yoursipcloud.com] to event
630. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1288 sofia/default/1234@00133897.yoursipcloud.com EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
631. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_channel.c:1288 sofia/default/1234@00133897.yoursipcloud.com EXPORTING[export_vars] [ring_group_transfer]=[true] to event
632. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
633. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables
634. 2024-12-16 13:10:33.415278 98.00% [NOTICE] switch_channel.c:1142 New Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 [d26a1f01-ab81-4459-9112-353da36a8170]
635. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_sofia.c:5110 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State Change CS_NEW -> CS_INIT
636. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_ivr_originate.c:3167 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Setting leg timeout to 30
637. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_INIT (Cur 18 Tot 1668)
638. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State INIT
639. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_sofia.c:97 sofia/default/31626673579@00133897.yoursipcloud.com:5060 SOFIA INIT
640. 2024-12-16 13:10:33.415278 98.00% [INFO] sofia_glue.c:1659 sofia/default/31626673579@00133897.yoursipcloud.com:5060 sending invite call-id: (null)
641. 2024-12-16 13:10:33.415278 98.00% [DEBUG] sofia_glue.c:1662 sofia/default/31626673579@00133897.yoursipcloud.com:5060 sending invite version: 1.10.10-release  64bit
642. Local SDP:
643. v=0
644. o=FreeSWITCH 1734331693 1734331694 IN IP4 185.249.40.236
645. s=FreeSWITCH
646. c=IN IP4 185.249.40.236
647. t=0 0
648. m=audio 22940 RTP/AVP 8 101
649. a=rtpmap:8 PCMA/8000
650. a=rtpmap:101 telephone-event/8000
651. a=fmtp:101 0-15
652. a=ptime:20
653. a=sendrecv
654.  
655. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:40 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Standard INIT
656. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:48 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State Change CS_INIT -> CS_ROUTING
657. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State INIT going to sleep
658. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_ROUTING (Cur 18 Tot 1668)
659. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State ROUTING
660. 2024-12-16 13:10:33.415278 98.00% [DEBUG] mod_sofia.c:158 sofia/default/31626673579@00133897.yoursipcloud.com:5060 SOFIA ROUTING
661. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_ivr_originate.c:67 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
662. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State ROUTING going to sleep
663. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_CONSUME_MEDIA (Cur 18 Tot 1668)
664. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:659 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State CONSUME_MEDIA
665. 2024-12-16 13:10:33.415278 98.00% [DEBUG] switch_core_state_machine.c:659 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State CONSUME_MEDIA going to sleep
666. 2024-12-16 13:10:33.415278 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 entering state [calling][0]
667. 2024-12-16 13:10:33.435278 98.00% [NOTICE] switch_channel.c:1142 New Channel sofia/default/318002666436@185.249.40.236:5060 [6ee6b425-d7a1-4dab-af7d-d7722a62ecb1]
668. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_NEW (Cur 19 Tot 1669)
669. 2024-12-16 13:10:33.435278 98.00% [INFO] sofia.c:10459 sofia/default/318002666436@185.249.40.236:5060 receiving invite from 185.249.40.236:5060 version: 1.10.10-release  64bit call-id: DLGCH_CzwiLRsWGwYjIjIpN2IKPgEZNF8dKwk+HRg8NiQfAxV3Qy4UAWI1FhUTJgMRfR05CUkjKDcq
670. 2024-12-16 13:10:33.435278 98.00% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 185.249.40.236:0.
671. 2024-12-16 13:10:33.435278 98.00% [DEBUG] sofia.c:10582 IP 185.249.40.236 Approved by acl "default[]". Access Granted.
672. 2024-12-16 13:10:33.435278 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/318002666436@185.249.40.236:5060 entering state [received][100]
673. 2024-12-16 13:10:33.435278 98.00% [DEBUG] sofia.c:7503 Remote SDP:
674. v=0
675. o=FreeSWITCH 1734331693 1734331694 IN IP4 185.249.40.236
676. s=FreeSWITCH
677. c=IN IP4 185.249.40.236
678. t=0 0
679. m=audio 22940 RTP/AVP 8 101
680. a=rtpmap:8 PCMA/8000
681. a=rtpmap:101 telephone-event/8000
682. a=fmtp:101 0-15
683. a=ptime:20
684.  
685. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
686. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
687. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
688. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
689. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
690. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/318002666436@185.249.40.236:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
691. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_codec.c:111 sofia/default/318002666436@185.249.40.236:5060 Original read codec set to PCMA:8
692. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
693. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_media.c:5856 sofia/default/318002666436@185.249.40.236:5060 Set 2833 dtmf send payload to 101 recv payload to 101
694. 2024-12-16 13:10:33.435278 98.00% [DEBUG] sofia.c:7927 (sofia/default/318002666436@185.249.40.236:5060) State Change CS_NEW -> CS_INIT
695. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:600 (sofia/default/318002666436@185.249.40.236:5060) State NEW
696. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_INIT (Cur 19 Tot 1669)
697. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/318002666436@185.249.40.236:5060) State INIT
698. 2024-12-16 13:10:33.435278 98.00% [DEBUG] mod_sofia.c:97 sofia/default/318002666436@185.249.40.236:5060 SOFIA INIT
699. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:40 sofia/default/318002666436@185.249.40.236:5060 Standard INIT
700. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:48 (sofia/default/318002666436@185.249.40.236:5060) State Change CS_INIT -> CS_ROUTING
701. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/318002666436@185.249.40.236:5060) State INIT going to sleep
702. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_ROUTING (Cur 19 Tot 1669)
703. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_channel.c:2399 (sofia/default/318002666436@185.249.40.236:5060) Callstate Change DOWN -> RINGING
704. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/318002666436@185.249.40.236:5060) State ROUTING
705. 2024-12-16 13:10:33.435278 98.00% [DEBUG] mod_sofia.c:158 sofia/default/318002666436@185.249.40.236:5060 SOFIA ROUTING
706. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_core_state_machine.c:230 sofia/default/318002666436@185.249.40.236:5060 Standard ROUTING
707. 2024-12-16 13:10:33.435278 98.00% [INFO] mod_dialplan_xml.c:639 Processing TEST <318002666436>-><null>31626673579 in context default
708. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
709. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
710. 2024-12-16 13:10:33.435278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
711. 2024-12-16 13:10:33.435278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : <null>31626673579
712. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '<null>31626673579' limit 1
713. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '<null>31626673579' AND status=0 limit 1
714. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '<null>31626673579' and status=0 LIMIT 1
715. 2024-12-16 13:10:33.435278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 318002666436 / 318002666436
716. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="<null>31626673579" LIMIT 1
717. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="<null>31626673579"  AND domains.domain="00133897.yoursipcloud.com" limit 1
718. 2024-12-16 13:10:33.435278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
719. 2024-12-16 13:10:33.435278 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 263083]
720. 2024-12-16 13:10:33.435278 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "263083" AND deleted = 0 limit 1
721. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^<null>31626673579.*' OR blocked_patterns = '^<null>3162667357.*' OR blocked_patterns = '^<null>316266735.*' OR blocked_patterns = '^<null>31626673.*' OR blocked_patterns = '^<null>3162667.*' OR blocked_patterns = '^<null>316266.*' OR blocked_patterns = '^<null>31626.*' OR blocked_patterns = '^<null>3162.*' OR blocked_patterns = '^<null>316.*' OR blocked_patterns = '^<null>31.*' OR blocked_patterns = '^<null>3.*' OR blocked_patterns = '^<null>.*' OR blocked_patterns = '^<null.*' OR blocked_patterns = '^<nul.*' OR blocked_patterns = '^<nu.*' OR blocked_patterns = '^<n.*' OR blocked_patterns = '^<.*' OR blocked_patterns ='--') AND accountid = 1061 limit 1
722. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="<null>31626673579" AND (accountid = 0 OR status = 1) LIMIT 1
723. 2024-12-16 13:10:33.455286 98.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
724. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1
725. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....<null>31626673579
726. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"0/31,+/".....<null>31626673579
727. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^<null>31626673579.*' OR patterns = '^<null>3162667357.*' OR patterns = '^<null>316266735.*' OR patterns = '^<null>31626673.*' OR patterns = '^<null>3162667.*' OR patterns = '^<null>316266.*' OR patterns = '^<null>31626.*' OR patterns = '^<null>3162.*' OR patterns = '^<null>316.*' OR patterns = '^<null>31.*' OR patterns = '^<null>3.*' OR patterns = '^<null>.*' OR patterns = '^<null.*' OR patterns = '^<nul.*' OR patterns = '^<nu.*' OR patterns = '^<n.*' OR patterns = '^<.*' OR patterns ='--') AND accountid = 1061 ORDER BY LENGTH(PKGPTR.patterns) DESC
728. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="<null>31626673579" AND A.accountid = '1061' limit 1
729. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : <null>31626673579
730. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,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,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="<null>31626673579" LIMIT 1
731. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.id as sip_id,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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="<null>31626673579"  AND domains.domain="00133897.yoursipcloud.com" limit 1
732. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
733. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '<null>31626673579' OR number = '<null>3162667357' OR number = '<null>316266735' OR number = '<null>31626673' OR number = '<null>3162667' OR number = '<null>316266' OR number = '<null>31626' OR number = '<null>3162' OR number = '<null>316' OR number = '<null>31' OR number = '<null>3' OR number = '<null>' OR number = '<null' OR number = '<nul' OR number = '<nu' OR number = '<n' OR number = '<' OR number ='--') OR (number = '<null>31626673579*' OR number = '<null>3162667357*' OR number = '<null>316266735*' OR number = '<null>31626673*' OR number = '<null>3162667*' OR number = '<null>316266*' OR number = '<null>31626*' OR number = '<null>3162*' OR number = '<null>316*' OR number = '<null>31*' OR number = '<null>3*' OR number = '<null>*' OR number = '<null*' OR number = '<nul*' OR number = '<nu*' OR number = '<n*' OR number = '<*' OR number ='--') OR (number = '318002666436' OR number = '31800266643' OR number = '3180026664' OR number = '318002666' OR number = '31800266' OR number = '3180026' OR number = '318002' OR number = '31800' OR number = '3180' OR number = '318' OR number = '31' OR number = '3' OR number ='--') OR (number = '318002666436*' OR number = '31800266643*' OR number = '3180026664*' OR number = '318002666*' OR number = '31800266*' OR number = '3180026*' OR number = '318002*' OR number = '31800*' OR number = '3180*' OR number = '318*' OR number = '31*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
734. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
735. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '<null>31626673579' OR routing_prefix = '<null>3162667357' OR routing_prefix = '<null>316266735' OR routing_prefix = '<null>31626673' OR routing_prefix = '<null>3162667' OR routing_prefix = '<null>316266' OR routing_prefix = '<null>31626' OR routing_prefix = '<null>3162' OR routing_prefix = '<null>316' OR routing_prefix = '<null>31' OR routing_prefix = '<null>3' OR routing_prefix = '<null>' OR routing_prefix = '<null' OR routing_prefix = '<nul' OR routing_prefix = '<nu' OR routing_prefix = '<n' OR routing_prefix = '<' OR routing_prefix ='--') AND status = 0 and reseller_id = 12 limit 1
736. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
737. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 1061
738. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 263083
739. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 8.614
740. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
741. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 6
742. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
743. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
744. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
745. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
746. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 6 AND status = 0
747. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^<null>31626673579.*' OR pattern = '^<null>3162667357.*' OR pattern = '^<null>316266735.*' OR pattern = '^<null>31626673.*' OR pattern = '^<null>3162667.*' OR pattern = '^<null>316266.*' OR pattern = '^<null>31626.*' OR pattern = '^<null>3162.*' OR pattern = '^<null>316.*' OR pattern = '^<null>31.*' OR pattern = '^<null>3.*' OR pattern = '^<null>.*' OR pattern = '^<null.*' OR pattern = '^<nul.*' OR pattern = '^<nu.*' OR pattern = '^<n.*' OR pattern = '^<.*' OR pattern ='--') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 6 OR accountid=1061)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC,effective_date DESC LIMIT 1
748. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
749. 2024-12-16 13:10:33.455286 98.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
750. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP]  Post Cdrs : 0
751. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET RESELLERID] Query :SELECT reseller_id FROM accounts WHERE id = 1061
752. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
753. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
754. <document type="freeswitch/xml">
755. <section name="dialplan" description="ASTPP Dialplan">
756. <context name="default">
757. <extension name="<null>31626673579">
758. <condition field="destination_number" expression="<null>31626673579">
759. <action application="log" data="WARNING Accountcode . Dialed number (<null>31626673579)  origination rates not found!!"/>
760. <action application="playback" data="/usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav"/>
761. <action application="set" data="original_caller_id_name=318002666436"/>
762. <action application="set" data="original_caller_id_number=318002666436"/>
763. <action application="set" data="error_cdr=1"/>
764. <action application="set" data="callstart=2024-12-16 13:10:33"/>
765. <action application="set" data="account_id=1061"/>
766. <action application="set" data="parent_id=12"/>
767. <action application="set" data="call_direction=outbound"/>
768. <action application="set" data="sip_ignore_remote_cause=true"/>
769. <action application="set" data="call_processed=internal"/>
770. <action application="set" data="effective_destination_number=<null>31626673579"/>
771. <action application="set" data="last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND"/>
772. <action application="hangup" data="ORIGINATION_RATE_NOT_FOUND"/>
773. </condition>
774. </extension>
775. </context>
776. </section>
777. </document>
778. Dialplan: sofia/default/318002666436@185.249.40.236:5060 parsing [default-><null>31626673579] continue=false
779. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Regex (PASS) [<null>31626673579] destination_number(<null>31626673579) =~ /<null>31626673579/ break=on-false
780. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action log(WARNING Accountcode . Dialed number (<null>31626673579)  origination rates not found!!)
781. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action playback(/usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav)
782. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(original_caller_id_name=318002666436)
783. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(original_caller_id_number=318002666436)
784. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(error_cdr=1)
785. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(callstart=2024-12-16 13:10:33)
786. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(account_id=1061)
787. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(parent_id=12)
788. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(call_direction=outbound)
789. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(sip_ignore_remote_cause=true)
790. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(call_processed=internal)
791. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(effective_destination_number=<null>31626673579)
792. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
793. Dialplan: sofia/default/318002666436@185.249.40.236:5060 Action hangup(ORIGINATION_RATE_NOT_FOUND)
794. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_state_machine.c:281 (sofia/default/318002666436@185.249.40.236:5060) State Change CS_ROUTING -> CS_EXECUTE
795. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/318002666436@185.249.40.236:5060) State ROUTING going to sleep
796. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_EXECUTE (Cur 19 Tot 1669)
797. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/318002666436@185.249.40.236:5060) State EXECUTE
798. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_sofia.c:213 sofia/default/318002666436@185.249.40.236:5060 SOFIA EXECUTE
799. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_state_machine.c:323 sofia/default/318002666436@185.249.40.236:5060 Standard EXECUTE
800. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 log(WARNING Accountcode . Dialed number (<null>31626673579)  origination rates not found!!)
801. 2024-12-16 13:10:33.455286 98.00% [WARNING] mod_dptools.c:1865 Accountcode . Dialed number (<null>31626673579)  origination rates not found!!
802. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_session.c:2791 Application playback Requires media! pre_answering channel sofia/default/318002666436@185.249.40.236:5060
803. 2024-12-16 13:10:33.455286 98.00% [INFO] switch_core_session.c:2793 Sending early media
804. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/318002666436@185.249.40.236:5060] 185.249.40.236 port 24510 -> 185.249.40.236 port 22940 codec: 8 ms: 20
805. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
806. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_media.c:8972 sofia/default/318002666436@185.249.40.236:5060 Set 2833 dtmf send payload to 101
807. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_media.c:8979 sofia/default/318002666436@185.249.40.236:5060 Set 2833 dtmf receive payload to 101
808. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_core_media.c:9002 sofia/default/318002666436@185.249.40.236:5060 Set rtp dtmf delay to 40
809. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_sofia.c:2607 Ring SDP:
810. v=0
811. o=FreeSWITCH 1734330123 1734330124 IN IP4 185.249.40.236
812. s=FreeSWITCH
813. c=IN IP4 185.249.40.236
814. t=0 0
815. m=audio 24510 RTP/AVP 8 101
816. a=rtpmap:8 PCMA/8000
817. a=rtpmap:101 telephone-event/8000
818. a=fmtp:101 0-15
819. a=ptime:20
820. a=sendrecv
821.  
822. 2024-12-16 13:10:33.455286 98.00% [NOTICE] mod_sofia.c:2610 Pre-Answer sofia/default/318002666436@185.249.40.236:5060!
823. 2024-12-16 13:10:33.455286 98.00% [DEBUG] switch_channel.c:3585 (sofia/default/318002666436@185.249.40.236:5060) Callstate Change RINGING -> EARLY
824. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 playback(/usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav)
825. 2024-12-16 13:10:33.455286 98.00% [WARNING] mod_sndfile.c:281 Error Opening File [/usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav] [System error : No such file or directory.]
826. 2024-12-16 13:10:33.455286 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/318002666436@185.249.40.236:5060 entering state [early][183]
827. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(original_caller_id_name=318002666436)
828. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [original_caller_id_name]=[318002666436]
829. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(original_caller_id_number=318002666436)
830. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [original_caller_id_number]=[318002666436]
831. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(error_cdr=1)
832. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [error_cdr]=[1]
833. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(callstart=2024-12-16 13:10:33)
834. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [callstart]=[2024-12-16 13:10:33]
835. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(account_id=1061)
836. 2024-12-16 13:10:33.455286 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [account_id]=[1061]
837. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(parent_id=12)
838. 2024-12-16 13:10:33.475277 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 entering state [proceeding][183]
839. 2024-12-16 13:10:33.475277 98.00% [DEBUG] sofia.c:7503 Remote SDP:
840. v=0
841. o=FreeSWITCH 1734330123 1734330124 IN IP4 185.249.40.236
842. s=FreeSWITCH
843. c=IN IP4 185.249.40.236
844. t=0 0
845. m=audio 24510 RTP/AVP 8 101
846. a=rtpmap:8 PCMA/8000
847. a=rtpmap:101 telephone-event/8000
848. a=fmtp:101 0-15
849. a=ptime:20
850.  
851. 2024-12-16 13:10:33.475277 98.00% [NOTICE] sofia.c:7506 Pre-Answer sofia/default/31626673579@00133897.yoursipcloud.com:5060!
852. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [parent_id]=[12]
853. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(call_direction=outbound)
854. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [call_direction]=[outbound]
855. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_channel.c:3585 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Callstate Change DOWN -> EARLY
856. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(sip_ignore_remote_cause=true)
857. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
858. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
859. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
860. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/31626673579@00133897.yoursipcloud.com:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
861. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_codec.c:111 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Original read codec set to PCMA:8
862. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [sip_ignore_remote_cause]=[true]
863. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
864. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:5856 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Set 2833 dtmf send payload to 101 recv payload to 101
865. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/31626673579@00133897.yoursipcloud.com:5060] 185.249.40.236 port 22940 -> 185.249.40.236 port 24510 codec: 8 ms: 20
866. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(call_processed=internal)
867. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
868. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [call_processed]=[internal]
869. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(effective_destination_number=<null>31626673579)
870. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [effective_destination_number]=[<null>31626673579]
871. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
872. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/318002666436@185.249.40.236:5060 [last_bridge_hangup_cause]=[ORIGINATION_RATE_NOT_FOUND]
873. EXECUTE [depth=0] sofia/default/318002666436@185.249.40.236:5060 hangup(ORIGINATION_RATE_NOT_FOUND)
874. 2024-12-16 13:10:33.475277 98.00% [NOTICE] mod_dptools.c:1374 Hangup sofia/default/318002666436@185.249.40.236:5060 [CS_EXECUTE] [NORMAL_CLEARING]
875. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_session.c:2979 sofia/default/318002666436@185.249.40.236:5060 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
876. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:8972 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Set 2833 dtmf send payload to 101
877. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/318002666436@185.249.40.236:5060) State EXECUTE going to sleep
878. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:8979 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Set 2833 dtmf receive payload to 101
879. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_HANGUP (Cur 19 Tot 1669)
880. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_media.c:9002 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Set rtp dtmf delay to 40
881. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/318002666436@185.249.40.236:5060) Callstate Change EARLY -> HANGUP
882. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/318002666436@185.249.40.236:5060) State HANGUP
883. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/318002666436@185.249.40.236:5060 hanging up, cause: NORMAL_CLEARING
884. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 480
885. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/318002666436@185.249.40.236:5060 Standard HANGUP, cause: NORMAL_CLEARING
886. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/318002666436@185.249.40.236:5060) State HANGUP going to sleep
887. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/318002666436@185.249.40.236:5060) State Change CS_HANGUP -> CS_REPORTING
888. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_REPORTING (Cur 19 Tot 1669)
889. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/318002666436@185.249.40.236:5060) State REPORTING
890. 2024-12-16 13:10:33.475277 98.00% [INFO] mod_json_cdr.c:272 Process [6ee6b425-d7a1-4dab-af7d-d7722a62ecb1.cdr.json]
891. 2024-12-16 13:10:33.475277 98.00% [DEBUG] sofia.c:6672 Remote Reason: 16
892. 2024-12-16 13:10:33.475277 98.00% [DEBUG] sofia.c:7493 Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 entering state [terminated][480]
893. 2024-12-16 13:10:33.475277 98.00% [NOTICE] sofia.c:8735 Hangup sofia/default/31626673579@00133897.yoursipcloud.com:5060 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
894. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_HANGUP (Cur 19 Tot 1669)
895. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Callstate Change EARLY -> HANGUP
896. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State HANGUP
897. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 hanging up, cause: NORMAL_CLEARING
898. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Standard HANGUP, cause: NORMAL_CLEARING
899. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State HANGUP going to sleep
900. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State Change CS_HANGUP -> CS_REPORTING
901. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_REPORTING (Cur 19 Tot 1669)
902. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State REPORTING
903. 2024-12-16 13:10:33.475277 98.00% [INFO] mod_json_cdr.c:272 Process [d26a1f01-ab81-4459-9112-353da36a8170.cdr.json]
904. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Standard REPORTING, cause: NORMAL_CLEARING
905. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State REPORTING going to sleep
906. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State Change CS_REPORTING -> CS_DESTROY
907. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_session.c:1744 Session 1668 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Locked, Waiting on external entities
908. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_ivr_originate.c:4052 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
909. 2024-12-16 13:10:33.475277 98.00% [NOTICE] switch_core_session.c:1762 Session 1668 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Ended
910. 2024-12-16 13:10:33.475277 98.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/31626673579@00133897.yoursipcloud.com:5060 [CS_DESTROY]
911. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) Running State Change CS_DESTROY (Cur 18 Tot 1669)
912. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State DESTROY
913. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:380 sofia/default/31626673579@00133897.yoursipcloud.com:5060 SOFIA DESTROY
914. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/31626673579@00133897.yoursipcloud.com:5060 Standard DESTROY
915. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/31626673579@00133897.yoursipcloud.com:5060) State DESTROY going to sleep
916. 2024-12-16 13:10:33.475277 98.00% [INFO] mod_dptools.c:3635 Originate Failed.  Cause: NORMAL_CLEARING
917. EXECUTE [depth=0] sofia/default/1234@00133897.yoursipcloud.com set(original_did_number=1884)
918. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_dptools.c:1671 SET sofia/default/1234@00133897.yoursipcloud.com [original_did_number]=[1884]
919. 2024-12-16 13:10:33.475277 98.00% [NOTICE] switch_core_state_machine.c:382 sofia/default/1234@00133897.yoursipcloud.com has executed the last dialplan instruction, hanging up.
920. 2024-12-16 13:10:33.475277 98.00% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/1234@00133897.yoursipcloud.com [CS_EXECUTE] [NORMAL_CLEARING]
921. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/1234@00133897.yoursipcloud.com) State EXECUTE going to sleep
922. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_HANGUP (Cur 18 Tot 1669)
923. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1234@00133897.yoursipcloud.com) Callstate Change RINGING -> HANGUP
924. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1234@00133897.yoursipcloud.com) State HANGUP
925. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:463 sofia/default/1234@00133897.yoursipcloud.com Overriding SIP cause 480 with 480 from the other leg
926. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:469 Channel sofia/default/1234@00133897.yoursipcloud.com hanging up, cause: NORMAL_CLEARING
927. 2024-12-16 13:10:33.475277 98.00% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 480
928. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:59 sofia/default/1234@00133897.yoursipcloud.com Standard HANGUP, cause: NORMAL_CLEARING
929. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1234@00133897.yoursipcloud.com) State HANGUP going to sleep
930. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_HANGUP -> CS_REPORTING
931. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_REPORTING (Cur 18 Tot 1669)
932. 2024-12-16 13:10:33.475277 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1234@00133897.yoursipcloud.com) State REPORTING
933. 2024-12-16 13:10:33.475277 98.00% [INFO] mod_json_cdr.c:272 Process [cef11b45-f79f-4ae3-98d8-7e10f9a444c4.cdr.json]
934. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/318002666436@185.249.40.236:5060 Standard REPORTING, cause: NORMAL_CLEARING
935. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/318002666436@185.249.40.236:5060) State REPORTING going to sleep
936. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/318002666436@185.249.40.236:5060) State Change CS_REPORTING -> CS_DESTROY
937. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_session.c:1744 Session 1669 (sofia/default/318002666436@185.249.40.236:5060) Locked, Waiting on external entities
938. 2024-12-16 13:10:33.535280 98.00% [NOTICE] switch_core_session.c:1762 Session 1669 (sofia/default/318002666436@185.249.40.236:5060) Ended
939. 2024-12-16 13:10:33.535280 98.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/318002666436@185.249.40.236:5060 [CS_DESTROY]
940. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/318002666436@185.249.40.236:5060) Running State Change CS_DESTROY (Cur 17 Tot 1669)
941. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/318002666436@185.249.40.236:5060) State DESTROY
942. 2024-12-16 13:10:33.535280 98.00% [DEBUG] mod_sofia.c:380 sofia/default/318002666436@185.249.40.236:5060 SOFIA DESTROY
943. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/318002666436@185.249.40.236:5060 Standard DESTROY
944. 2024-12-16 13:10:33.535280 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/318002666436@185.249.40.236:5060) State DESTROY going to sleep
945. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:168 sofia/default/1234@00133897.yoursipcloud.com Standard REPORTING, cause: NORMAL_CLEARING
946. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1234@00133897.yoursipcloud.com) State REPORTING going to sleep
947. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1234@00133897.yoursipcloud.com) State Change CS_REPORTING -> CS_DESTROY
948. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_session.c:1744 Session 1667 (sofia/default/1234@00133897.yoursipcloud.com) Locked, Waiting on external entities
949. 2024-12-16 13:10:33.555279 98.00% [NOTICE] switch_core_session.c:1762 Session 1667 (sofia/default/1234@00133897.yoursipcloud.com) Ended
950. 2024-12-16 13:10:33.555279 98.00% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/1234@00133897.yoursipcloud.com [CS_DESTROY]
951. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1234@00133897.yoursipcloud.com) Running State Change CS_DESTROY (Cur 16 Tot 1669)
952. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1234@00133897.yoursipcloud.com) State DESTROY
953. 2024-12-16 13:10:33.555279 98.00% [DEBUG] mod_sofia.c:380 sofia/default/1234@00133897.yoursipcloud.com SOFIA DESTROY
954. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:175 sofia/default/1234@00133897.yoursipcloud.com Standard DESTROY
955. 2024-12-16 13:10:33.555279 98.00% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1234@00133897.yoursipcloud.com) State DESTROY going to sleep
956.