1. span style="color: cornflowerblue; font-weight: bold;"> 2024-01-22 09:04:53.678586 36.23% [NOTICE] switch_channel.c:1123 New Channel sofia/default/8999@astpplight.astppbilling.org [01bbc876-3a29-4c0a-a872-f3354dbcb114]
2. 2024-01-22 09:04:53.678586 36.23% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_NEW (Cur 1 Tot 784)
3. 2024-01-22 09:04:53.678586 36.23% [INFO] sofia.c:10453 sofia/default/8999@astpplight.astppbilling.org receiving invite from 103.240.35.46:33998 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: YjVmM2E4MmZiMWQ0MzkwOTgzMGFlZWQ4Njg1ODc4YjQ.
4. 2024-01-22 09:04:53.678586 36.23% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 103.240.35.46:0.
5. 2024-01-22 09:04:53.678586 36.23% [WARNING] sofia_reg.c:1846 SIP auth challenge (INVITE) on sofia profile 'default' for [5566778899@astpplight.astppbilling.org] from ip 103.240.35.46
6. 2024-01-22 09:04:53.678586 36.23% [DEBUG] switch_core_state_machine.c:600 (sofia/default/8999@astpplight.astppbilling.org) State NEW
7. 2024-01-22 09:04:53.678586 36.23% [DEBUG] sofia.c:2419 detaching session 01bbc876-3a29-4c0a-a872-f3354dbcb114
8. 2024-01-22 09:04:54.598579 37.10% [DEBUG] sofia.c:2532 Re-attaching to session 01bbc876-3a29-4c0a-a872-f3354dbcb114
9. 2024-01-22 09:04:54.618588 37.10% [INFO] sofia.c:10453 sofia/default/8999@astpplight.astppbilling.org receiving invite from 103.240.35.46:33998 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: YjVmM2E4MmZiMWQ0MzkwOTgzMGFlZWQ4Njg1ODc4YjQ.
10. 2024-01-22 09:04:54.618588 37.10% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 103.240.35.46:0.
11. 2024-01-22 09:04:54.618588 37.10% [DEBUG] sofia.c:11668 Setting NAT mode based on via received
12. 2024-01-22 09:04:54.618588 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [received][100]
13. 2024-01-22 09:04:54.618588 37.10% [DEBUG] sofia.c:7497 Remote SDP:
14. v=0
15. o=Z 0 0 IN IP4 103.240.35.46
16. s=Z
17. c=IN IP4 103.240.35.46
18. t=0 0
19. m=audio 8000 RTP/AVP 3 110 8 0 98 101
20. a=rtpmap:110 speex/8000
21. a=rtpmap:98 iLBC/8000
22. a=fmtp:98 mode=20
23. a=rtpmap:101 telephone-event/8000
24. a=fmtp:101 0-15
25.  
26. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
27. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
28. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
29. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
30. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
31. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
32. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
33. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
34. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
35. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
36. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
37. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
38. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
39. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/8999@astpplight.astppbilling.org PCMA/8000 20 ms 160 samples 64000 bits 1 channels
40. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_codec.c:111 sofia/default/8999@astpplight.astppbilling.org Original read codec set to PCMA:8
41. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
42. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_media.c:5850 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf send payload to 101 recv payload to 101
43. 2024-01-22 09:04:54.618588 37.10% [DEBUG] sofia.c:7921 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_NEW -> CS_INIT
44. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_INIT (Cur 1 Tot 784)
45. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8999@astpplight.astppbilling.org) State INIT
46. 2024-01-22 09:04:54.618588 37.10% [DEBUG] mod_sofia.c:97 sofia/default/8999@astpplight.astppbilling.org SOFIA INIT
47. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/8999@astpplight.astppbilling.org Standard INIT
48. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_INIT -> CS_ROUTING
49. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8999@astpplight.astppbilling.org) State INIT going to sleep
50. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_ROUTING (Cur 1 Tot 784)
51. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_channel.c:2380 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change DOWN -> RINGING
52. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING
53. 2024-01-22 09:04:54.618588 37.10% [DEBUG] mod_sofia.c:158 sofia/default/8999@astpplight.astppbilling.org SOFIA ROUTING
54. 2024-01-22 09:04:54.618588 37.10% [DEBUG] switch_core_state_machine.c:230 sofia/default/8999@astpplight.astppbilling.org Standard ROUTING
55. 2024-01-22 09:04:54.618588 37.10% [INFO] mod_dialplan_xml.c:639 Processing 8999 <8999>->5566778899 in context default
56. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
57. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
58. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
59. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
60. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 5566778899
61. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
62. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER8999
63. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
64. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '5566778899' limit 1
65. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 8999 / 8999
66. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
67. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="5566778899" limit 1                                                                                                                                                                    
68. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
69. 2024-01-22 09:04:54.638577 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 6081200124]
70. 2024-01-22 09:04:54.638577 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "6081200124" OR id = "6081200124") AND deleted = 0 limit 1
71. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^5566778899.*' OR blocked_patterns = '^556677889.*' OR blocked_patterns = '^55667788.*' OR blocked_patterns = '^5566778.*' OR blocked_patterns = '^556677.*' OR blocked_patterns = '^55667.*' OR blocked_patterns = '^5566.*' OR blocked_patterns = '^556.*' OR blocked_patterns = '^55.*' OR blocked_patterns = '^5.*' OR blocked_patterns ='--') AND accountid = 11 limit 1                                                                                                                
72. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
73. 2024-01-22 09:04:54.798574 37.10% [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 = '^5566778899.*' OR patterns = '^556677889.*' OR patterns = '^55667788.*' OR patterns = '^5566778.*' OR patterns = '^556677.*' OR patterns = '^55667.*' OR patterns = '^5566.*' OR patterns = '^556.*' OR patterns = '^55.*' OR patterns = '^5.*' OR patterns ='--') AND accountid = 11 ORDER BY LENGTH(PKGPTR.patterns) DESC                          
74. 2024-01-22 09:04:54.798574 37.10% [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 ="5566778899" AND A.accountid = '11' limit 1                                                                                                                                                                  
75. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 5566778899
76. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
77. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="5566778899" limit 1                                                                                                                                                                    
78. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
79. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '5566778899' OR number = '556677889' OR number = '55667788' OR number = '5566778' OR number = '556677' OR number = '55667' OR number = '5566' OR number = '556' OR number = '55' OR number = '5' OR number ='--') OR (number = '5566778899*' OR number = '556677889*' OR number = '55667788*' OR number = '5566778*' OR number = '556677*' OR number = '55667*' OR number = '5566*' OR number = '556*' OR number = '55*' OR number = '5*' OR number ='--') OR (number = '8999' OR number = '899' OR number = '89' OR number = '8' OR number ='--') OR (number = '8999*' OR number = '899*' OR number = '89*' OR number = '8*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC                                                                                                                                          
80. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
81. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '5566778899' OR routing_prefix = '556677889' OR routing_prefix = '55667788' OR routing_prefix = '5566778' OR routing_prefix = '556677' OR routing_prefix = '55667' OR routing_prefix = '5566' OR routing_prefix = '556' OR routing_prefix = '55' OR routing_prefix = '5' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1                                                                                                              
82. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
83. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 11
84. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 6081200124
85. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 633.34267
86. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
87. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 5
88. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
89. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
90. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
91. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
92. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
93. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
94. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
95. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^5566778899.*' OR pattern = '^556677889.*' OR pattern = '^55667788.*' OR pattern = '^5566778.*' OR pattern = '^556677.*' OR pattern = '^55667.*' OR pattern = '^5566.*' OR pattern = '^556.*' OR pattern = '^55.*' OR pattern = '^5.*' OR pattern ='--') AND (caller_id_pattern = '^8999.*' OR caller_id_pattern = '^899.*' OR caller_id_pattern = '^89.*' OR caller_id_pattern = '^8.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 5 OR accountid=11)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1                                                                                                        
96. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
97. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
98. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 8
99. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
100. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
101. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
102. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment :
103. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
104. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 11
105. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
106. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
107. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
108. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your633.34267 balance Accountid 11 !!!
109. 2024-01-22 09:04:54.798574 37.10% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
110. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
111. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
112. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=5 ORDER by id asc
113. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^5566778899.*' OR pattern = '^556677889.*' OR pattern = '^55667788.*' OR pattern = '^5566778.*' OR pattern = '^556677.*' OR pattern = '^55667.*' OR pattern = '^5566.*' OR pattern = '^556.*' OR pattern = '^55.*' OR pattern = '^5.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (1,7) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC                                                                                                                  
114. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
115. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 31
116. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^55.*
117. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
118. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
119. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
120. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^55.*
121. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
122. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
123. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
124. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
125. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
126. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
127. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
128. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
129. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
130. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
131. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 27
132. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^55.*
133. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
134. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
135. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
136. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^55.*
137. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
138. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
139. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 7
140. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Dell
141. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
142. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
143. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 9
144. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 1
145. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
146. 2024-01-22 09:04:54.798574 37.10% [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 = 11 AND status=0 LIMIT 1                                                                                                                                                                                        
147. 2024-01-22 09:04:54.798574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
148. 2024-01-22 09:04:54.798574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = '8999' and status = 0 limit 1
149. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
150. 2024-01-22 09:04:54.818574 37.10% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
151. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
152. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
153. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 3) AND status=0 limit 1                                                                                                                                                                  
154. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 1) AND status=0 limit 1                                                                                                                                                                    
155. 2024-01-22 09:04:54.818574 37.10% [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5566778899" LIMIT 1                                      
156. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID_CALL_TRANSFER] Query :SELECT A.id as id,A.provider_id as provider_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,A.transfer_status,A.busy_status,A.no_answer_status,A.always_status,A.transfer_call_type,A.transfer_extensions,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5566778899" LIMIT 1                                                                                                                                                  
157. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
158. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
159. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN :
160. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Enable/Disable:0
161. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
162. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
163. 2024-01-22 09:04:54.818574 37.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] User applicable_on_outbound_calls : 0
164. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 1
165. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 0
166. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [tmp_value]: 1
167. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [first_prefix]: 5
168. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
169. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
170. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 9) AND status=0 limit 1                                                                                                                                                                  
171. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 7) AND status=0 limit 1                                                                                                                                                                    
172. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec  FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="8999" limit 1                                                                                                      
173. 2024-01-22 09:04:54.818574 37.10% [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5566778899" LIMIT 1                                      
174. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID_CALL_TRANSFER] Query :SELECT A.id as id,A.provider_id as provider_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,A.transfer_status,A.busy_status,A.no_answer_status,A.always_status,A.transfer_call_type,A.transfer_extensions,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5566778899" LIMIT 1                                                                                                                                                  
175. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
176. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
177. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN :
178. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Enable/Disable:0
179. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
180. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
181. 2024-01-22 09:04:54.818574 37.10% [WARNING] switch_cpp.cpp:1465 [ASTPP] User applicable_on_outbound_calls : 0
182. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 1
183. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 0
184. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [tmp_value]: 1
185. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [first_prefix]: 5
186. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
187. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
188. <document type="freeswitch/xml">                                                                                                                                                                                  
189. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
190. <context name="default">                                                                                                                                                                                          
191. <extension name="5566778899">                                                                                                                                                                                      
192. <condition field="destination_number" expression="5566778899">                                                                                                                                                    
193. <action application="set" data="effective_destination_number=5566778899"/>                                                                                                                                        
194. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>                                                                                                                                        
195. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>                                                                                                                              
196. <action application="set" data="notify_flag=0"/>                                                                                                                                                                  
197. <action application="set" data="notify_credit_limit=5.00000"/>                                                                                                                                                    
198. <action application="set" data="type=0"/>                                                                                                                                                                          
199. <action application="set" data="callstart=2024-01-22 09:04:54"/>                                                                                                                                                  
200. <action application="set" data="hangup_after_bridge=true"/>                                                                                                                                                        
201. <action application="set" data="continue_on_fail=TRUE"/>                                                                                                                                                          
202. <action application="set" data="account_id=11"/>                                                                                                                                                                  
203. <action application="set" data="parent_id=0"/>                                                                                                                                                                    
204. <action application="set" data="entity_id=0"/>                                                                                                                                                                    
205. <action application="set" data="call_processed=internal"/>                                                                                                                                                        
206. <action application="set" data="call_direction=outbound"/>                                                                                                                                                        
207. <action application="set" data="accountname=default"/>                                                                                                                                                            
208. <action application="set" data="origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11"/>                
209. <action application="set" data="original_caller_id_name=8999"/>                                                                                                                                                    
210. <action application="set" data="original_caller_id_number=8999"/>                                                                                                                                                  
211.  
212. <action application="set" data="effective_caller_id_name=8999"/>                                                                                                                                                  
213. <action application="set" data="effective_caller_id_number=8999"/>                                                                                                                                                
214. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
215. <action application="set" data="termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3"/>                                  
216. <action application="set" data="trunk_id=1"/>                                                                                                                                                                      
217. <action application="set" data="provider_id=3"/>                                                                                                                                                                  
218. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
219. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
220. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
221. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
222. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
223. <action application="set" data="quality_trunk_seq=1,7"/>                                                                                                                                                          
224. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
225. <action application="export" data="presence_data=trunk_id=1"/>                                                                                                                                                    
226. <action application="set" data="intcall=0"/>                                                                                                                                                                      
227. <action application="export" data="presence_id=5566778899"/>                                                                                                                                                      
228. <action application="export" data="presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46"/>                                      
229. <action application="set" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                                  
230. <action application="export" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                              
231. <action application="set" data="stir_status=0"/>                                                                                                                                                                  
232. <action application="set" data="sip_stir_shaken_attest=NA"/>                                                                                                                                                      
233. <action application="bridge" data="{leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5566778899"/>                                                                                                                                                                                                                
234. <action application="set" data="effective_caller_id_name=8999"/>                                                                                                                                                  
235. <action application="set" data="effective_caller_id_number=8999"/>                                                                                                                                                
236. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
237. <action application="set" data="termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9"/>                                  
238. <action application="set" data="trunk_id=7"/>                                                                                                                                                                      
239. <action application="set" data="provider_id=9"/>                                                                                                                                                                  
240. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
241. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
242. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
243. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
244. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
245. <action application="set" data="quality_trunk_seq=1,7"/>                                                                                                                                                          
246. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
247. <action application="export" data="presence_data=trunk_id=7"/>                                                                                                                                                    
248. <action application="set" data="intcall=0"/>                                                                                                                                                                      
249. <action application="export" data="presence_id=5566778899"/>                                                                                                                                                      
250. <action application="export" data="presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46"/>                                        
251. <action application="set" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                                  
252. <action application="export" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                              
253. <action application="set" data="stir_status=0"/>                                                                                                                                                                  
254. <action application="set" data="sip_stir_shaken_attest=NA"/>                                                                                                                                                      
255. <action application="limit" data="db Dell gw_Dell 1 "/>                                                                                                                                                            
256. <action application="bridge" data="{leg_timeout=0,sip_stir_shaken_attest=NA}[leg_timeout=0]sofia/gateway/Dell/5566778899"/>                                                                                        
257. </condition>                                                                                                                                                                                                      
258. </extension>                                                                                                                                                                                                      
259. </context>                                                                                                                                                                                                        
260. </section>                                                                                                                                                                                                        
261. </document>                                                                                                                                                                                                        
262. Dialplan: sofia/default/8999@astpplight.astppbilling.org parsing [default->5566778899] continue=false
263. Dialplan: sofia/default/8999@astpplight.astppbilling.org Regex (PASS) [5566778899] destination_number(5566778899) =~ /5566778899/ break=on-false
264. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_destination_number=5566778899)
265. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(bridge_pre_execute_bleg_app=sched_hangup)
266. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
267. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(notify_flag=0)
268. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(notify_credit_limit=5.00000)
269. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(type=0)
270. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(callstart=2024-01-22 09:04:54)
271. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(hangup_after_bridge=true)
272. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(continue_on_fail=TRUE)
273. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(account_id=11)
274. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(parent_id=0)
275. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(entity_id=0)
276. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(call_processed=internal)
277. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(call_direction=outbound)
278. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(accountname=default)
279. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11)                                                                                                                                                                                                
280. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(original_caller_id_name=8999)
281. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(original_caller_id_number=8999)
282. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_name=8999)
283. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_number=8999)
284. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(calltype=STANDARD)
285. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3)
286. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(trunk_id=1)
287. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(provider_id=3)
288. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(rate_flag=0)
289. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_credit_limit=0.00000)
290. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_credit_limit=0.00000)
291. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_used_limit=0.00000)
292. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_used_limit=0.00000)
293. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(quality_trunk_seq=1,7)
294. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(force_trunk_flag=0)
295. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=trunk_id=1)
296. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(intcall=0)
297. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_id=5566778899)
298. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46)
299. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
300. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
301. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(stir_status=0)
302. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_stir_shaken_attest=NA)
303. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action bridge({leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5566778899)                                                                                                                                                                              
304. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_name=8999)
305. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_number=8999)
306. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(calltype=STANDARD)
307. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9)
308. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(trunk_id=7)
309. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(provider_id=9)
310. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(rate_flag=0)
311. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_credit_limit=0.00000)
312. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_credit_limit=0.00000)
313. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_used_limit=0.00000)
314. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_used_limit=0.00000)
315. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(quality_trunk_seq=1,7)
316. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(force_trunk_flag=0)
317. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=trunk_id=7)
318. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(intcall=0)
319. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_id=5566778899)
320. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46)
321. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
322. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
323. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(stir_status=0)
324. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_stir_shaken_attest=NA)
325. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action limit(db Dell gw_Dell 1 )
326. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action bridge({leg_timeout=0,sip_stir_shaken_attest=NA}[leg_timeout=0]sofia/gateway/Dell/5566778899)
327. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_core_state_machine.c:281 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_ROUTING -> CS_EXECUTE
328. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING going to sleep
329. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_EXECUTE (Cur 1 Tot 784)
330. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE
331. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_sofia.c:213 sofia/default/8999@astpplight.astppbilling.org SOFIA EXECUTE
332. 2024-01-22 09:04:54.818574 37.10% [DEBUG] switch_core_state_machine.c:323 sofia/default/8999@astpplight.astppbilling.org Standard EXECUTE
333. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_destination_number=5566778899)
334. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_destination_number]=[5566778899]
335. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(bridge_pre_execute_bleg_app=sched_hangup)
336. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [bridge_pre_execute_bleg_app]=[sched_hangup]
337. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
338. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
339. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(notify_flag=0)
340. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [notify_flag]=[0]
341. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(notify_credit_limit=5.00000)
342. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [notify_credit_limit]=[5.00000]
343. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(type=0)
344. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [type]=[0]
345. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(callstart=2024-01-22 09:04:54)
346. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [callstart]=[2024-01-22 09:04:54]
347. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(hangup_after_bridge=true)
348. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [hangup_after_bridge]=[true]
349. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(continue_on_fail=TRUE)
350. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [continue_on_fail]=[TRUE]
351. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(account_id=11)
352. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [account_id]=[11]
353. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(parent_id=0)
354. 2024-01-22 09:04:54.818574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [parent_id]=[0]
355. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(entity_id=0)
356. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [entity_id]=[0]
357. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(call_processed=internal)
358. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [call_processed]=[internal]
359. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(call_direction=outbound)
360. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [call_direction]=[outbound]
361. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(accountname=default)
362. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [accountname]=[default]
363. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11)                                                                                                                                                                                                
364. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [origination_rates]=[ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11]                                                                                                                                                  
365. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(original_caller_id_name=8999)
366. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [original_caller_id_name]=[8999]
367. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(original_caller_id_number=8999)
368. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [original_caller_id_number]=[8999]
369. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_name=8999)
370. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_name]=[8999]
371. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_number=8999)
372. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_number]=[8999]
373. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(calltype=STANDARD)
374. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [calltype]=[STANDARD]
375. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3)
376. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [termination_rates]=[ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3]                                                                                                                                                                    
377. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(trunk_id=1)
378. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [trunk_id]=[1]
379. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(provider_id=3)
380. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [provider_id]=[3]
381. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(rate_flag=0)
382. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [rate_flag]=[0]
383. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_credit_limit=0.00000)
384. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_credit_limit]=[0.00000]
385. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_credit_limit=0.00000)
386. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_credit_limit]=[0.00000]
387. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_used_limit=0.00000)
388. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_used_limit]=[0.00000]
389. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_used_limit=0.00000)
390. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_used_limit]=[0.00000]
391. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(quality_trunk_seq=1,7)
392. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [quality_trunk_seq]=[1,7]
393. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(force_trunk_flag=0)
394. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [force_trunk_flag]=[0]
395. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=trunk_id=1)
396. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
397. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(intcall=0)
398. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [intcall]=[0]
399. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_id=5566778899)
400. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[5566778899]
401. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46)
402. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46]                                                                                                                                                                                                      
403. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
404. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
405. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
406. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
407. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(stir_status=0)
408. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [stir_status]=[0]
409. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_stir_shaken_attest=NA)
410. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_stir_shaken_attest]=[NA]
411. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org bridge({leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5566778899)                                                                                                                                                                              
412. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46] to event                                                                                                                                            
413. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_id]=[5566778899] to event
414. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46] to event                                                                                                                                            
415. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable] to event
416. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
417. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_ivr_originate.c:2853 Parsing session specific variables
418. 2024-01-22 09:04:54.838579 37.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/5566778899 [b02b890e-608c-4450-8a2a-8722c52d537c]
419. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_sofia.c:5117 (sofia/default/5566778899) State Change CS_NEW -> CS_INIT
420. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_ivr_originate.c:3161 sofia/default/5566778899 Setting leg timeout to 30
421. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_INIT (Cur 2 Tot 785)
422. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5566778899) State INIT
423. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_sofia.c:97 sofia/default/5566778899 SOFIA INIT
424. 2024-01-22 09:04:54.838579 37.10% [INFO] sofia_glue.c:1654 sofia/default/5566778899 sending invite call-id: (null)
425. 2024-01-22 09:04:54.838579 37.10% [DEBUG] sofia_glue.c:1657 sofia/default/5566778899 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
426. Local SDP:                                                                                                                                                                                                        
427. v=0                                                                                                                                                                                                                
428. o=FreeSWITCH 1705891740 1705891741 IN IP4 95.217.215.250                                                                                                                                                          
429. s=FreeSWITCH                                                                                                                                                                                                      
430. c=IN IP4 95.217.215.250                                                                                                                                                                                            
431. t=0 0                                                                                                                                                                                                              
432. m=audio 22554 RTP/AVP 8 18 101                                                                                                                                                                                    
433. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
434. a=rtpmap:18 G729/8000                                                                                                                                                                                              
435. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
436. a=fmtp:101 0-15                                                                                                                                                                                                    
437. a=ptime:20                                                                                                                                                                                                        
438. a=sendrecv                                                                                                                                                                                                        
439.  
440. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/5566778899 Standard INIT
441. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/5566778899) State Change CS_INIT -> CS_ROUTING
442. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5566778899) State INIT going to sleep
443. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_ROUTING (Cur 2 Tot 785)
444. 2024-01-22 09:04:54.838579 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [calling][0]
445. 2024-01-22 09:04:54.838579 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [terminated][503]
446. 2024-01-22 09:04:54.838579 37.10% [NOTICE] sofia.c:8729 Hangup sofia/default/5566778899 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
447. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5566778899) State ROUTING
448. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_sofia.c:158 sofia/default/5566778899 SOFIA ROUTING
449. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5566778899) State ROUTING going to sleep
450. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_HANGUP (Cur 2 Tot 785)
451. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:844 (sofia/default/5566778899) Callstate Change DOWN -> HANGUP
452. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5566778899) State HANGUP
453. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_sofia.c:468 Channel sofia/default/5566778899 hanging up, cause: NORMAL_TEMPORARY_FAILURE
454. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:59 sofia/default/5566778899 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
455. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5566778899) State HANGUP going to sleep
456. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:616 (sofia/default/5566778899) State Change CS_HANGUP -> CS_REPORTING
457. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_REPORTING (Cur 2 Tot 785)
458. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5566778899) State REPORTING
459. 2024-01-22 09:04:54.838579 37.10% [INFO] mod_json_cdr.c:272 Process [b02b890e-608c-4450-8a2a-8722c52d537c.cdr.json]
460. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
461. 2024-01-22 09:04:54.838579 37.10% [INFO] mod_dptools.c:3637 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
462. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_name=8999)
463. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_name]=[8999]
464. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_number=8999)
465. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_number]=[8999]
466. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(calltype=STANDARD)
467. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [calltype]=[STANDARD]
468. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9)
469. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [termination_rates]=[ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9]                                                                                                                                                                    
470. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(trunk_id=7)
471. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [trunk_id]=[7]
472. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(provider_id=9)
473. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [provider_id]=[9]
474. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(rate_flag=0)
475. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [rate_flag]=[0]
476. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_credit_limit=0.00000)
477. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_credit_limit]=[0.00000]
478. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_credit_limit=0.00000)
479. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_credit_limit]=[0.00000]
480. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_used_limit=0.00000)
481. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_used_limit]=[0.00000]
482. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_used_limit=0.00000)
483. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_used_limit]=[0.00000]
484. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(quality_trunk_seq=1,7)
485. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [quality_trunk_seq]=[1,7]
486. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(force_trunk_flag=0)
487. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [force_trunk_flag]=[0]
488. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=trunk_id=7)
489. 2024-01-22 09:04:54.838579 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=7]
490. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(intcall=0)
491. 2024-01-22 09:04:54.838579 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [intcall]=[0]
492. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_id=5566778899)
493. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[5566778899]
494. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46)
495. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46]                                                                                                                                                                                                        
496. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
497. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
498. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
499. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
500. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(stir_status=0)
501. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [stir_status]=[0]
502. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_stir_shaken_attest=NA)
503. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_stir_shaken_attest]=[NA]
504. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org limit(db Dell gw_Dell 1 )
505. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_limit.c:124 incr called: Dell_gw_Dell max:1, interval:0
506. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_db.c:194 Usage for Dell_gw_Dell is now 1/1
507. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org bridge({leg_timeout=0,sip_stir_shaken_attest=NA}[leg_timeout=0]sofia/gateway/Dell/5566778899)
508. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46] to event                                                                                                                                              
509. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_id]=[5566778899] to event
510. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46] to event                                                                                                                                              
511. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable] to event
512. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46] to event                                                                                                                                              
513. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_id]=[5566778899] to event
514. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46] to event                                                                                                                                              
515. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable] to event
516. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
517. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_ivr_originate.c:2853 Parsing session specific variables
518. 2024-01-22 09:04:54.858574 37.10% [NOTICE] switch_channel.c:1123 New Channel sofia/default/5566778899 [bbaaa2da-014b-407d-b118-9fdebbfd2320]
519. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_sofia.c:5117 (sofia/default/5566778899) State Change CS_NEW -> CS_INIT
520. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_INIT (Cur 3 Tot 786)
521. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5566778899) State INIT
522. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_sofia.c:97 sofia/default/5566778899 SOFIA INIT
523. 2024-01-22 09:04:54.858574 37.10% [INFO] sofia_glue.c:1654 sofia/default/5566778899 sending invite call-id: (null)
524. 2024-01-22 09:04:54.858574 37.10% [DEBUG] sofia_glue.c:1657 sofia/default/5566778899 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
525. Local SDP:                                                                                                                                                                                                        
526. v=0                                                                                                                                                                                                                
527. o=FreeSWITCH 1705889626 1705889627 IN IP4 95.217.215.250                                                                                                                                                          
528. s=FreeSWITCH                                                                                                                                                                                                      
529. c=IN IP4 95.217.215.250                                                                                                                                                                                            
530. t=0 0                                                                                                                                                                                                              
531. m=audio 24668 RTP/AVP 8 101                                                                                                                                                                                        
532. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
533. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
534. a=fmtp:101 0-15                                                                                                                                                                                                    
535. a=ptime:20                                                                                                                                                                                                        
536. a=sendrecv                                                                                                                                                                                                        
537.  
538. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:40 sofia/default/5566778899 Standard INIT
539. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:48 (sofia/default/5566778899) State Change CS_INIT -> CS_ROUTING
540. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5566778899) State INIT going to sleep
541. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_ROUTING (Cur 3 Tot 786)
542. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5566778899) State ROUTING
543. 2024-01-22 09:04:54.858574 37.10% [DEBUG] mod_sofia.c:158 sofia/default/5566778899 SOFIA ROUTING
544. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_ivr_originate.c:67 (sofia/default/5566778899) State Change CS_ROUTING -> CS_CONSUME_MEDIA
545. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5566778899) State ROUTING going to sleep
546. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 786)
547. 2024-01-22 09:04:54.858574 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [calling][0]
548. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/5566778899) State CONSUME_MEDIA
549. 2024-01-22 09:04:54.858574 37.10% [DEBUG] switch_core_state_machine.c:659 (sofia/default/5566778899) State CONSUME_MEDIA going to sleep
550. 2024-01-22 09:04:55.038584 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [proceeding][180]
551. 2024-01-22 09:04:55.038584 37.10% [NOTICE] sofia.c:7598 Ring-Ready sofia/default/5566778899!
552. 2024-01-22 09:04:55.038584 37.10% [DEBUG] switch_channel.c:3494 (sofia/default/5566778899) Callstate Change DOWN -> RINGING
553. 2024-01-22 09:04:55.058580 37.10% [NOTICE] mod_sofia.c:2516 Ring-Ready sofia/default/8999@astpplight.astppbilling.org!
554. 2024-01-22 09:04:55.058580 37.10% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [early][180]
555. 2024-01-22 09:04:55.058580 37.10% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/default/8999@astpplight.astppbilling.org!
556. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:168 sofia/default/5566778899 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
557. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5566778899) State REPORTING going to sleep
558. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:607 (sofia/default/5566778899) State Change CS_REPORTING -> CS_DESTROY
559. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_session.c:1743 Session 785 (sofia/default/5566778899) Locked, Waiting on external entities
560. 2024-01-22 09:04:55.058580 37.10% [NOTICE] switch_core_session.c:1761 Session 785 (sofia/default/5566778899) Ended
561. 2024-01-22 09:04:55.058580 37.10% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/5566778899 [CS_DESTROY]
562. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:735 (sofia/default/5566778899) Running State Change CS_DESTROY (Cur 2 Tot 786)
563. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5566778899) State DESTROY
564. 2024-01-22 09:04:55.058580 37.10% [DEBUG] mod_sofia.c:379 sofia/default/5566778899 SOFIA DESTROY
565. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:175 sofia/default/5566778899 Standard DESTROY
566. 2024-01-22 09:04:55.058580 37.10% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5566778899) State DESTROY going to sleep
567. 2024-01-22 09:04:59.618584 36.37% [WARNING] sofia_reg.c:1846 SIP auth challenge (INVITE) on sofia profile 'default' for [6081200124@astpplight.astppbilling.org] from ip 103.240.35.46
568. 2024-01-22 09:05:00.058577 36.37% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [completing][200]
569. 2024-01-22 09:05:00.058577 36.37% [DEBUG] sofia.c:7497 Remote SDP:
570. v=0                                                                                                                                                                                                                
571. o=root 1562423905 1562423905 IN IP4 13.201.89.233                                                                                                                                                                  
572. s=Asterisk PBX 18.20.0                                                                                                                                                                                            
573. c=IN IP4 13.201.89.233                                                                                                                                                                                            
574. t=0 0                                                                                                                                                                                                              
575. m=audio 17526 RTP/AVP 8 101                                                                                                                                                                                        
576. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
577. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
578. a=fmtp:101 0-16                                                                                                                                                                                                    
579. a=maxptime:150                                                                                                                                                                                                    
580.  
581. 2024-01-22 09:05:00.058577 36.37% [DEBUG] sofia.c:7487 Channel sofia/default/5566778899 entering state [ready][200]
582. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:150:64000:1]/[PCMA:8:8000:20:64000:1]
583. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
584. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
585. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/5566778899 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
586. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_codec.c:111 sofia/default/5566778899 Original read codec set to PCMA:8
587. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
588. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:5850 sofia/default/5566778899 Set 2833 dtmf send payload to 101 recv payload to 101
589. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/5566778899] 95.217.215.250 port 24668 -> 13.201.89.233 port 17526 codec: 8 ms: 20
590. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
591. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8963 sofia/default/5566778899 Set 2833 dtmf send payload to 101
592. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8970 sofia/default/5566778899 Set 2833 dtmf receive payload to 101
593. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8993 sofia/default/5566778899 Set rtp dtmf delay to 40
594. 2024-01-22 09:05:00.058577 36.37% [NOTICE] sofia.c:8674 Channel [sofia/default/5566778899] has been answered
595. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_channel.c:3893 (sofia/default/5566778899) Callstate Change RINGING -> ACTIVE
596. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/8999@astpplight.astppbilling.org] 95.217.215.250 port 23382 -> 103.240.35.46 port 8000 codec: 8 ms: 20
597. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
598. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8963 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf send payload to 101
599. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8970 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf receive payload to 101
600. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_core_media.c:8993 sofia/default/8999@astpplight.astppbilling.org Set rtp dtmf delay to 40
601. 2024-01-22 09:05:00.058577 36.37% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/8999@astpplight.astppbilling.org:
602. v=0                                                                                                                                                                                                                
603. o=FreeSWITCH 1705890918 1705890919 IN IP4 95.217.215.250                                                                                                                                                          
604. s=FreeSWITCH                                                                                                                                                                                                      
605. c=IN IP4 95.217.215.250                                                                                                                                                                                            
606. t=0 0                                                                                                                                                                                                              
607. m=audio 23382 RTP/AVP 8 101                                                                                                                                                                                        
608. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
609. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
610. a=fmtp:101 0-15                                                                                                                                                                                                    
611. a=ptime:20                                                                                                                                                                                                        
612. a=sendrecv                                                                                                                                                                                                        
613.  
614. 2024-01-22 09:05:00.058577 36.37% [NOTICE] switch_ivr_originate.c:3844 Channel [sofia/default/8999@astpplight.astppbilling.org] has been answered
615. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_channel.c:3893 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change RINGING -> ACTIVE
616. 2024-01-22 09:05:00.058577 36.37% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [completed][200]
617. 2024-01-22 09:05:00.058577 36.37% [DEBUG] switch_ivr_originate.c:3902 Originate Resulted in Success: [sofia/default/5566778899] Peer UUID: bbaaa2da-014b-407d-b118-9fdebbfd2320
618. 2024-01-22 09:05:00.078574 36.37% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/5566778899) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
619. 2024-01-22 09:05:00.078574 36.37% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 786)
620. 2024-01-22 09:05:00.078574 36.37% [DEBUG] switch_core_state_machine.c:650 (sofia/default/5566778899) State EXCHANGE_MEDIA
621. 2024-01-22 09:05:00.078574 36.37% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
622. 2024-01-22 09:05:00.098578 36.37% [WARNING] sofia_reg.c:3211 Can't find user [6081200124@astpplight.astppbilling.org] from 103.240.35.46
623. You must define a domain called 'astpplight.astppbilling.org' in your directory and add a user with the id="6081200124" attribute                                                                                  
624. and you must configure your device to use the proper domain in its authentication credentials.                                                                                                                    
625. 2024-01-22 09:05:00.258582 36.23% [DEBUG] switch_ivr_bridge.c:654 sofia/default/5566778899 Bridge execute app sched_hangup(+6000 normal_clearing)
626. 2024-01-22 09:05:00.278583 36.23% [DEBUG] switch_ivr.c:632 sofia/default/5566778899 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
627. EXECUTE [depth=0] sofia/default/5566778899 sched_hangup(+6000 normal_clearing)
628. 2024-01-22 09:05:00.278583 36.23% [DEBUG] switch_scheduler.c:263 Added task 9 switch_ivr_schedule_hangup (bbaaa2da-014b-407d-b118-9fdebbfd2320) to run at 1705920300
629. 2024-01-22 09:05:00.438688 36.23% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [ready][200]
630. 2024-01-22 09:05:00.458576 36.23% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
631. 2024-01-22 09:05:00.598570 36.23% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
632. 2024-01-22 09:05:12.258581 33.23% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [received][100]
633. 2024-01-22 09:05:12.258581 33.23% [DEBUG] sofia.c:7497 Remote SDP:
634. v=0                                                                                                                                                                                                                
635. o=Z 0 1 IN IP4 103.240.35.46                                                                                                                                                                                      
636. s=Z                                                                                                                                                                                                                
637. c=IN IP4 0.0.0.0                                                                                                                                                                                                  
638. t=0 0                                                                                                                                                                                                              
639. m=audio 8000 RTP/AVP 3 110 8 0 98 101                                                                                                                                                                              
640. a=rtpmap:110 speex/8000                                                                                                                                                                                            
641. a=rtpmap:98 iLBC/8000                                                                                                                                                                                              
642. a=fmtp:98 mode=20                                                                                                                                                                                                  
643. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
644. a=fmtp:101 0-15                                                                                                                                                                                                    
645. a=inactive                                                                                                                                                                                                        
646.  
647. 2024-01-22 09:05:12.258581 33.23% [WARNING] switch_core_media.c:4918 RFC2543 from March 1999 called; They want their 0.0.0.0 hold method back.....
648. 2024-01-22 09:05:12.258581 33.23% [DEBUG] switch_channel.c:1975 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change ACTIVE -> HELD
649. 2024-01-22 09:05:12.418586 33.23% [DEBUG] switch_ivr.c:632 sofia/default/5566778899 Command Execute [depth=0] playback(local_stream://moh)
650. EXECUTE [depth=0] sofia/default/5566778899 playback(local_stream://moh)
651. 2024-01-22 09:05:12.418586 33.23% [DEBUG] mod_local_stream.c:912 Opening Stream [moh/8000] 8000hz
652. 2024-01-22 09:05:12.418586 33.23% [DEBUG] switch_ivr_play_say.c:1566 Codec Activated L16@8000hz 1 channels 20ms
653. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
654. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
655. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
656. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
657. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
658. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
659. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
660. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
661. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
662. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
663. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
664. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
665. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
666. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
667. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:5850 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf send payload to 101 recv payload to 101
668. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:8639 Audio params changed for sofia/default/8999@astpplight.astppbilling.org from 103.240.35.46:8000 to 0.0.0.0:8000
669. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/8999@astpplight.astppbilling.org] 95.217.215.250 port 23382 -> 0.0.0.0 port 8000 codec: 8 ms: 20
670. 2024-01-22 09:05:12.518582 33.23% [DEBUG] switch_core_media.c:8680 AUDIO RTP CHANGING DEST TO: [0.0.0.0:8000]
671. 2024-01-22 09:05:12.518582 33.23% [DEBUG] sofia.c:8442 Processing updated SDP
672. 2024-01-22 09:05:12.538598 33.23% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [completed][200]
673. 2024-01-22 09:05:12.938588 33.23% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [ready][200]
674. 2024-01-22 09:05:13.278575 33.20% [NOTICE] switch_channel.c:1123 New Channel sofia/default/8999@astpplight.astppbilling.org [2c6f4f06-dfa2-4f26-9ae6-de750ade17f6]
675. 2024-01-22 09:05:13.278575 33.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_NEW (Cur 3 Tot 787)
676. 2024-01-22 09:05:13.278575 33.20% [INFO] sofia.c:10453 sofia/default/8999@astpplight.astppbilling.org receiving invite from 103.240.35.46:33998 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: ZjllYjIxMzlhZDU5OWZiNzE2ZTBmMWNmMzQ1NzNiYjU.                                                                                                                                                                  
677. 2024-01-22 09:05:13.278575 33.20% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 103.240.35.46:0.
678. 2024-01-22 09:05:13.278575 33.20% [WARNING] sofia_reg.c:1846 SIP auth challenge (INVITE) on sofia profile 'default' for [5544332211@astpplight.astppbilling.org] from ip 103.240.35.46
679. 2024-01-22 09:05:13.278575 33.20% [DEBUG] switch_core_state_machine.c:600 (sofia/default/8999@astpplight.astppbilling.org) State NEW
680. 2024-01-22 09:05:13.278575 33.20% [DEBUG] sofia.c:2419 detaching session 2c6f4f06-dfa2-4f26-9ae6-de750ade17f6
681. 2024-01-22 09:05:14.078578 33.20% [DEBUG] sofia.c:2532 Re-attaching to session 2c6f4f06-dfa2-4f26-9ae6-de750ade17f6
682. 2024-01-22 09:05:14.098583 33.20% [INFO] sofia.c:10453 sofia/default/8999@astpplight.astppbilling.org receiving invite from 103.240.35.46:33998 version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit call-id: ZjllYjIxMzlhZDU5OWZiNzE2ZTBmMWNmMzQ1NzNiYjU.                                                                                                                                                                  
683. 2024-01-22 09:05:14.098583 33.20% [DEBUG] sofia.c:10547 verifying acl "default" for ip/port 103.240.35.46:0.
684. 2024-01-22 09:05:14.118579 33.20% [DEBUG] sofia.c:11668 Setting NAT mode based on via received
685. 2024-01-22 09:05:14.118579 33.20% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [received][100]
686. 2024-01-22 09:05:14.118579 33.20% [DEBUG] sofia.c:7497 Remote SDP:
687. v=0                                                                                                                                                                                                                
688. o=Z 0 0 IN IP4 103.240.35.46                                                                                                                                                                                      
689. s=Z                                                                                                                                                                                                                
690. c=IN IP4 103.240.35.46                                                                                                                                                                                            
691. t=0 0                                                                                                                                                                                                              
692. m=audio 8002 RTP/AVP 3 110 8 0 98 101                                                                                                                                                                              
693. a=rtpmap:110 speex/8000                                                                                                                                                                                            
694. a=rtpmap:98 iLBC/8000                                                                                                                                                                                              
695. a=fmtp:98 mode=20                                                                                                                                                                                                  
696. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
697. a=fmtp:101 0-15                                                                                                                                                                                                    
698.  
699. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
700. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
701. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
702. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
703. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
704. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
705. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
706. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
707. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
708. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5582 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
709. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
710. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5527 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
711. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5443 Set telephone-event payload to 101@8000
712. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:3750 Set Codec sofia/default/8999@astpplight.astppbilling.org PCMA/8000 20 ms 160 samples 64000 bits 1 channels
713. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_codec.c:111 sofia/default/8999@astpplight.astppbilling.org Original read codec set to PCMA:8
714. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5792 Set telephone-event payload to 101@8000
715. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_media.c:5850 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf send payload to 101 recv payload to 101
716. 2024-01-22 09:05:14.118579 33.20% [DEBUG] sofia.c:7921 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_NEW -> CS_INIT
717. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_INIT (Cur 3 Tot 787)
718. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8999@astpplight.astppbilling.org) State INIT
719. 2024-01-22 09:05:14.118579 33.20% [DEBUG] mod_sofia.c:97 sofia/default/8999@astpplight.astppbilling.org SOFIA INIT
720. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:40 sofia/default/8999@astpplight.astppbilling.org Standard INIT
721. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:48 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_INIT -> CS_ROUTING
722. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:624 (sofia/default/8999@astpplight.astppbilling.org) State INIT going to sleep
723. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_ROUTING (Cur 3 Tot 787)
724. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_channel.c:2380 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change DOWN -> RINGING
725. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING
726. 2024-01-22 09:05:14.118579 33.20% [DEBUG] mod_sofia.c:158 sofia/default/8999@astpplight.astppbilling.org SOFIA ROUTING
727. 2024-01-22 09:05:14.118579 33.20% [DEBUG] switch_core_state_machine.c:230 sofia/default/8999@astpplight.astppbilling.org Standard ROUTING
728. 2024-01-22 09:05:14.118579 33.20% [INFO] mod_dialplan_xml.c:639 Processing 8999 <8999>->5544332211 in context default
729. 2024-01-22 09:05:14.118579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
730. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
731. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
732. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
733. 2024-01-22 09:05:14.138579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 5544332211
734. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
735. 2024-01-22 09:05:14.138579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER8999
736. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
737. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '5544332211' limit 1
738. 2024-01-22 09:05:14.138579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 8999 / 8999
739. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
740. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="5544332211" limit 1                                                                                                                                                                    
741. 2024-01-22 09:05:14.138579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
742. 2024-01-22 09:05:14.138579 33.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 6081200124]
743. 2024-01-22 09:05:14.138579 33.20% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "6081200124" OR id = "6081200124") AND deleted = 0 limit 1
744. 2024-01-22 09:05:14.298585 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^5544332211.*' OR blocked_patterns = '^554433221.*' OR blocked_patterns = '^55443322.*' OR blocked_patterns = '^5544332.*' OR blocked_patterns = '^554433.*' OR blocked_patterns = '^55443.*' OR blocked_patterns = '^5544.*' OR blocked_patterns = '^554.*' OR blocked_patterns = '^55.*' OR blocked_patterns = '^5.*' OR blocked_patterns ='--') AND accountid = 11 limit 1                                                                                                                
745. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
746. 2024-01-22 09:05:14.318575 33.57% [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 = '^5544332211.*' OR patterns = '^554433221.*' OR patterns = '^55443322.*' OR patterns = '^5544332.*' OR patterns = '^554433.*' OR patterns = '^55443.*' OR patterns = '^5544.*' OR patterns = '^554.*' OR patterns = '^55.*' OR patterns = '^5.*' OR patterns ='--') AND accountid = 11 ORDER BY LENGTH(PKGPTR.patterns) DESC                          
747. 2024-01-22 09:05:14.318575 33.57% [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 ="5544332211" AND A.accountid = '11' limit 1                                                                                                                                                                  
748. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 5544332211
749. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
750. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="5544332211" limit 1                                                                                                                                                                    
751. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
752. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '5544332211' OR number = '554433221' OR number = '55443322' OR number = '5544332' OR number = '554433' OR number = '55443' OR number = '5544' OR number = '554' OR number = '55' OR number = '5' OR number ='--') OR (number = '5544332211*' OR number = '554433221*' OR number = '55443322*' OR number = '5544332*' OR number = '554433*' OR number = '55443*' OR number = '5544*' OR number = '554*' OR number = '55*' OR number = '5*' OR number ='--') OR (number = '8999' OR number = '899' OR number = '89' OR number = '8' OR number ='--') OR (number = '8999*' OR number = '899*' OR number = '89*' OR number = '8*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC                                                                                                                                          
753. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
754. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '5544332211' OR routing_prefix = '554433221' OR routing_prefix = '55443322' OR routing_prefix = '5544332' OR routing_prefix = '554433' OR routing_prefix = '55443' OR routing_prefix = '5544' OR routing_prefix = '554' OR routing_prefix = '55' OR routing_prefix = '5' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1                                                                                                              
755. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
756. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 11
757. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 6081200124
758. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 633.34267
759. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 0 [0:prepaid,1:postpaid]
760. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 5
761. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
762. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
763. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
764. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
765. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
766. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
767. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
768. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^5544332211.*' OR pattern = '^554433221.*' OR pattern = '^55443322.*' OR pattern = '^5544332.*' OR pattern = '^554433.*' OR pattern = '^55443.*' OR pattern = '^5544.*' OR pattern = '^554.*' OR pattern = '^55.*' OR pattern = '^5.*' OR pattern ='--') AND (caller_id_pattern = '^8999.*' OR caller_id_pattern = '^899.*' OR caller_id_pattern = '^89.*' OR caller_id_pattern = '^8.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 5 OR accountid=11)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1                                                                                                        
769. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
770. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
771. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 8
772. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
773. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
774. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
775. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment :
776. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
777. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 11
778. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
779. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
780. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
781. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your633.34267 balance Accountid 11 !!!
782. 2024-01-22 09:05:14.318575 33.57% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
783. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
784. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
785. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=5 ORDER by id asc
786. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^5544332211.*' OR pattern = '^554433221.*' OR pattern = '^55443322.*' OR pattern = '^5544332.*' OR pattern = '^554433.*' OR pattern = '^55443.*' OR pattern = '^5544.*' OR pattern = '^554.*' OR pattern = '^55.*' OR pattern = '^5.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (1,7) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC                                                                                                                  
787. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
788. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 31
789. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^55.*
790. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
791. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
792. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
793. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^55.*
794. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
795. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
796. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 1
797. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : YourProvider
798. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
799. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
800. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 3
801. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
802. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
803. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
804. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 27
805. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^55.*
806. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination :
807. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
808. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
809. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^55.*
810. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
811. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
812. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 7
813. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Dell
814. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
815. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
816. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 9
817. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 1
818. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
819. 2024-01-22 09:05:14.318575 33.57% [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 = 11 AND status=0 LIMIT 1                                                                                                                                                                                        
820. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
821. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = '8999' and status = 0 limit 1
822. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
823. 2024-01-22 09:05:14.318575 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
824. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
825. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
826. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 3) AND status=0 limit 1                                                                                                                                                                  
827. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 1) AND status=0 limit 1                                                                                                                                                                    
828. 2024-01-22 09:05:14.318575 33.57% [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5544332211" LIMIT 1                                      
829. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID_CALL_TRANSFER] Query :SELECT A.id as id,A.provider_id as provider_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,A.transfer_status,A.busy_status,A.no_answer_status,A.always_status,A.transfer_call_type,A.transfer_extensions,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5544332211" LIMIT 1                                                                                                                                                  
830. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
831. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
832. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN :
833. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Enable/Disable:0
834. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
835. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
836. 2024-01-22 09:05:14.318575 33.57% [WARNING] switch_cpp.cpp:1465 [ASTPP] User applicable_on_outbound_calls : 0
837. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 1
838. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 0
839. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [tmp_value]: 1
840. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [first_prefix]: 5
841. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
842. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::5
843. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 9) AND status=0 limit 1                                                                                                                                                                  
844. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 7) AND status=0 limit 1                                                                                                                                                                    
845. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [sip_codec_for_outbound] Query :SELECT sip_devices.codec as sip_codec  FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="8999" limit 1                                                                                                      
846. 2024-01-22 09:05:14.318575 33.57% [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, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5544332211" LIMIT 1                                      
847. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID_CALL_TRANSFER] Query :SELECT A.id as id,A.provider_id as provider_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,A.transfer_status,A.busy_status,A.no_answer_status,A.always_status,A.transfer_call_type,A.transfer_extensions,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="5544332211" LIMIT 1                                                                                                                                                  
848. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
849. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
850. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN :
851. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Enable/Disable:0
852. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
853. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [attestation] COMING FOR STIR/SHAKEN For Outbound:0
854. 2024-01-22 09:05:14.318575 33.57% [WARNING] switch_cpp.cpp:1465 [ASTPP] User applicable_on_outbound_calls : 0
855. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 1
856. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [outbound_call_prefixes]: 0
857. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [tmp_value]: 1
858. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [first_prefix]: 5
859. 2024-01-22 09:05:14.318575 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
860. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
861. <document type="freeswitch/xml">                                                                                                                                                                                  
862. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
863. <context name="default">                                                                                                                                                                                          
864. <extension name="5544332211">                                                                                                                                                                                      
865. <condition field="destination_number" expression="5544332211">                                                                                                                                                    
866. <action application="set" data="effective_destination_number=5544332211"/>                                                                                                                                        
867. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>                                                                                                                                        
868. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>                                                                                                                              
869. <action application="set" data="notify_flag=0"/>                                                                                                                                                                  
870. <action application="set" data="notify_credit_limit=5.00000"/>                                                                                                                                                    
871. <action application="set" data="type=0"/>                                                                                                                                                                          
872. <action application="set" data="callstart=2024-01-22 09:05:14"/>                                                                                                                                                  
873. <action application="set" data="hangup_after_bridge=true"/>                                                                                                                                                        
874. <action application="set" data="continue_on_fail=TRUE"/>                                                                                                                                                          
875. <action application="set" data="account_id=11"/>                                                                                                                                                                  
876. <action application="set" data="parent_id=0"/>                                                                                                                                                                    
877. <action application="set" data="entity_id=0"/>                                                                                                                                                                    
878. <action application="set" data="call_processed=internal"/>                                                                                                                                                        
879. <action application="set" data="call_direction=outbound"/>                                                                                                                                                        
880. <action application="set" data="accountname=default"/>                                                                                                                                                            
881. <action application="set" data="origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11"/>                
882. <action application="set" data="original_caller_id_name=8999"/>                                                                                                                                                    
883. <action application="set" data="original_caller_id_number=8999"/>                                                                                                                                                  
884.  
885. <action application="set" data="effective_caller_id_name=8999"/>                                                                                                                                                  
886. <action application="set" data="effective_caller_id_number=8999"/>                                                                                                                                                
887. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
888. <action application="set" data="termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3"/>                                  
889. <action application="set" data="trunk_id=1"/>                                                                                                                                                                      
890. <action application="set" data="provider_id=3"/>                                                                                                                                                                  
891. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
892. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
893. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
894. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
895. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
896. <action application="set" data="quality_trunk_seq=1,7"/>                                                                                                                                                          
897. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
898. <action application="export" data="presence_data=trunk_id=1"/>                                                                                                                                                    
899. <action application="set" data="intcall=0"/>                                                                                                                                                                      
900. <action application="export" data="presence_id=5544332211"/>                                                                                                                                                      
901. <action application="export" data="presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46"/>                                      
902. <action application="set" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                                  
903. <action application="export" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                              
904. <action application="set" data="stir_status=0"/>                                                                                                                                                                  
905. <action application="set" data="sip_stir_shaken_attest=NA"/>                                                                                                                                                      
906. <action application="bridge" data="{leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5544332211"/>                                                                                                                                                                                                                
907. <action application="set" data="effective_caller_id_name=8999"/>                                                                                                                                                  
908. <action application="set" data="effective_caller_id_number=8999"/>                                                                                                                                                
909. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
910. <action application="set" data="termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9"/>                                  
911. <action application="set" data="trunk_id=7"/>                                                                                                                                                                      
912. <action application="set" data="provider_id=9"/>                                                                                                                                                                  
913. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
914. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
915. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
916. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
917. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
918. <action application="set" data="quality_trunk_seq=1,7"/>                                                                                                                                                          
919. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
920. <action application="export" data="presence_data=trunk_id=7"/>                                                                                                                                                    
921. <action application="set" data="intcall=0"/>                                                                                                                                                                      
922. <action application="export" data="presence_id=5544332211"/>                                                                                                                                                      
923. <action application="export" data="presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46"/>                                        
924. <action application="set" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                                  
925. <action application="export" data="sip_h_X-ASTPP-Stir-Verification=Not Applicable"/>                                                                                                                              
926. <action application="set" data="stir_status=0"/>                                                                                                                                                                  
927. <action application="set" data="sip_stir_shaken_attest=NA"/>                                                                                                                                                      
928. <action application="limit" data="db Dell gw_Dell 1 "/>                                                                                                                                                            
929. <action application="bridge" data="{leg_timeout=0,sip_stir_shaken_attest=NA}[leg_timeout=0]sofia/gateway/Dell/5544332211"/>                                                                                        
930. </condition>                                                                                                                                                                                                      
931. </extension>                                                                                                                                                                                                      
932. </context>                                                                                                                                                                                                        
933. </section>                                                                                                                                                                                                        
934. </document>                                                                                                                                                                                                        
935. Dialplan: sofia/default/8999@astpplight.astppbilling.org parsing [default->5544332211] continue=false
936. Dialplan: sofia/default/8999@astpplight.astppbilling.org Regex (PASS) [5544332211] destination_number(5544332211) =~ /5544332211/ break=on-false
937. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_destination_number=5544332211)
938. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(bridge_pre_execute_bleg_app=sched_hangup)
939. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
940. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(notify_flag=0)
941. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(notify_credit_limit=5.00000)
942. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(type=0)
943. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(callstart=2024-01-22 09:05:14)
944. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(hangup_after_bridge=true)
945. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(continue_on_fail=TRUE)
946. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(account_id=11)
947. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(parent_id=0)
948. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(entity_id=0)
949. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(call_processed=internal)
950. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(call_direction=outbound)
951. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(accountname=default)
952. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11)                                                                                                                                                                                                
953. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(original_caller_id_name=8999)
954. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(original_caller_id_number=8999)
955. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_name=8999)
956. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_number=8999)
957. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(calltype=STANDARD)
958. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3)
959. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(trunk_id=1)
960. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(provider_id=3)
961. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(rate_flag=0)
962. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_credit_limit=0.00000)
963. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_credit_limit=0.00000)
964. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_used_limit=0.00000)
965. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_used_limit=0.00000)
966. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(quality_trunk_seq=1,7)
967. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(force_trunk_flag=0)
968. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=trunk_id=1)
969. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(intcall=0)
970. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_id=5544332211)
971. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46)
972. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
973. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
974. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(stir_status=0)
975. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_stir_shaken_attest=NA)
976. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action bridge({leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5544332211)                                                                                                                                                                              
977. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_name=8999)
978. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_caller_id_number=8999)
979. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(calltype=STANDARD)
980. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9)
981. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(trunk_id=7)
982. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(provider_id=9)
983. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(rate_flag=0)
984. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_credit_limit=0.00000)
985. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_credit_limit=0.00000)
986. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(daily_call_used_limit=0.00000)
987. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(monthly_call_used_limit=0.00000)
988. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(quality_trunk_seq=1,7)
989. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(force_trunk_flag=0)
990. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=trunk_id=7)
991. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(intcall=0)
992. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_id=5544332211)
993. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46)
994. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
995. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
996. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(stir_status=0)
997. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(sip_stir_shaken_attest=NA)
998. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action limit(db Dell gw_Dell 1 )
999. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action bridge({leg_timeout=0,sip_stir_shaken_attest=NA}[leg_timeout=0]sofia/gateway/Dell/5544332211)
1000. 2024-01-22 09:05:14.338580 33.57% [DEBUG] switch_core_state_machine.c:281 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_ROUTING -> CS_EXECUTE
1001. 2024-01-22 09:05:14.338580 33.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING going to sleep
1002. 2024-01-22 09:05:14.338580 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_EXECUTE (Cur 3 Tot 787)
1003. 2024-01-22 09:05:14.338580 33.57% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE
1004. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_sofia.c:213 sofia/default/8999@astpplight.astppbilling.org SOFIA EXECUTE
1005. 2024-01-22 09:05:14.338580 33.57% [DEBUG] switch_core_state_machine.c:323 sofia/default/8999@astpplight.astppbilling.org Standard EXECUTE
1006. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_destination_number=5544332211)
1007. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_destination_number]=[5544332211]
1008. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(bridge_pre_execute_bleg_app=sched_hangup)
1009. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [bridge_pre_execute_bleg_app]=[sched_hangup]
1010. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
1011. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
1012. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(notify_flag=0)
1013. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [notify_flag]=[0]
1014. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(notify_credit_limit=5.00000)
1015. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [notify_credit_limit]=[5.00000]
1016. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(type=0)
1017. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [type]=[0]
1018. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(callstart=2024-01-22 09:05:14)
1019. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [callstart]=[2024-01-22 09:05:14]
1020. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(hangup_after_bridge=true)
1021. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [hangup_after_bridge]=[true]
1022. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(continue_on_fail=TRUE)
1023. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [continue_on_fail]=[TRUE]
1024. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(account_id=11)
1025. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [account_id]=[11]
1026. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(parent_id=0)
1027. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [parent_id]=[0]
1028. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(entity_id=0)
1029. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [entity_id]=[0]
1030. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(call_processed=internal)
1031. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [call_processed]=[internal]
1032. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(call_direction=outbound)
1033. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [call_direction]=[outbound]
1034. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(accountname=default)
1035. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [accountname]=[default]
1036. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(origination_rates=ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11)                                                                                                                                                                                                
1037. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [origination_rates]=[ID:8|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.00000|INC:1|INITIALBLOCK:1|RATEGROUP:5|MARKUP:0|CI:0|ACCID:11]                                                                                                                                                  
1038. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(original_caller_id_name=8999)
1039. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [original_caller_id_name]=[8999]
1040. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(original_caller_id_number=8999)
1041. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [original_caller_id_number]=[8999]
1042. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_name=8999)
1043. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_name]=[8999]
1044. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_number=8999)
1045. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_number]=[8999]
1046. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(calltype=STANDARD)
1047. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [calltype]=[STANDARD]
1048. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(termination_rates=ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3)
1049. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [termination_rates]=[ID:31|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:0|INITIALBLOCK:1|TRUNK:1|PROVIDER:3]                                                                                                                                                                    
1050. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(trunk_id=1)
1051. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [trunk_id]=[1]
1052. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(provider_id=3)
1053. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [provider_id]=[3]
1054. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(rate_flag=0)
1055. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [rate_flag]=[0]
1056. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_credit_limit=0.00000)
1057. 2024-01-22 09:05:14.338580 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_credit_limit]=[0.00000]
1058. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_credit_limit=0.00000)
1059. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_credit_limit]=[0.00000]
1060. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_used_limit=0.00000)
1061. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_used_limit]=[0.00000]
1062. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_used_limit=0.00000)
1063. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_used_limit]=[0.00000]
1064. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(quality_trunk_seq=1,7)
1065. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [quality_trunk_seq]=[1,7]
1066. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(force_trunk_flag=0)
1067. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [force_trunk_flag]=[0]
1068. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=trunk_id=1)
1069. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=1]
1070. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(intcall=0)
1071. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [intcall]=[0]
1072. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_id=5544332211)
1073. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[5544332211]
1074. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46)
1075. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46]                                                                                                                                                                                                      
1076. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
1077. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
1078. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
1079. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
1080. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(stir_status=0)
1081. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [stir_status]=[0]
1082. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_stir_shaken_attest=NA)
1083. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_stir_shaken_attest]=[NA]
1084. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org bridge({leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA,sip_stir_shaken_attest=NA}[leg_timeout=30,absolute_codec_string=^^:PCMA:G729:PCMA]sofia/gateway/YourProvider/5544332211)                                                                                                                                                                              
1085. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46] to event                                                                                                                                            
1086. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_id]=[5544332211] to event
1087. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||YourTrunk // ^55.* //  // 0.00000 // trunk_id=1|||STD|||103.240.35.46] to event                                                                                                                                            
1088. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_channel.c:1269 sofia/default/8999@astpplight.astppbilling.org EXPORTING[export_vars] [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable] to event
1089. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_ivr_originate.c:2291 Parsing global variables
1090. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_ivr_originate.c:2853 Parsing session specific variables
1091. 2024-01-22 09:05:14.358576 33.57% [NOTICE] switch_channel.c:1123 New Channel sofia/default/5544332211 [3d1eae89-a256-40b3-99da-9135639408d4]
1092. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_sofia.c:5117 (sofia/default/5544332211) State Change CS_NEW -> CS_INIT
1093. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_ivr_originate.c:3161 sofia/default/5544332211 Setting leg timeout to 30
1094. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5544332211) Running State Change CS_INIT (Cur 4 Tot 788)
1095. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5544332211) State INIT
1096. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_sofia.c:97 sofia/default/5544332211 SOFIA INIT
1097. 2024-01-22 09:05:14.358576 33.57% [INFO] sofia_glue.c:1654 sofia/default/5544332211 sending invite call-id: (null)
1098. 2024-01-22 09:05:14.358576 33.57% [DEBUG] sofia_glue.c:1657 sofia/default/5544332211 sending invite version: 1.10.9-release git a615e85 2023-02-03 20:07:30Z 64bit
1099. Local SDP:                                                                                                                                                                                                        
1100. v=0                                                                                                                                                                                                                
1101. o=FreeSWITCH 1705886108 1705886109 IN IP4 95.217.215.250                                                                                                                                                          
1102. s=FreeSWITCH                                                                                                                                                                                                      
1103. c=IN IP4 95.217.215.250                                                                                                                                                                                            
1104. t=0 0                                                                                                                                                                                                              
1105. m=audio 28206 RTP/AVP 8 18 101                                                                                                                                                                                    
1106. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1107. a=rtpmap:18 G729/8000                                                                                                                                                                                              
1108. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1109. a=fmtp:101 0-15                                                                                                                                                                                                    
1110. a=ptime:20                                                                                                                                                                                                        
1111. a=sendrecv                                                                                                                                                                                                        
1112.  
1113. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:40 sofia/default/5544332211 Standard INIT
1114. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:48 (sofia/default/5544332211) State Change CS_INIT -> CS_ROUTING
1115. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:624 (sofia/default/5544332211) State INIT going to sleep
1116. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5544332211) Running State Change CS_ROUTING (Cur 4 Tot 788)
1117. 2024-01-22 09:05:14.358576 33.57% [DEBUG] sofia.c:7487 Channel sofia/default/5544332211 entering state [calling][0]
1118. 2024-01-22 09:05:14.358576 33.57% [DEBUG] sofia.c:7487 Channel sofia/default/5544332211 entering state [terminated][503]
1119. 2024-01-22 09:05:14.358576 33.57% [NOTICE] sofia.c:8729 Hangup sofia/default/5544332211 [CS_ROUTING] [NORMAL_TEMPORARY_FAILURE]
1120. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5544332211) State ROUTING
1121. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_sofia.c:158 sofia/default/5544332211 SOFIA ROUTING
1122. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/5544332211) State ROUTING going to sleep
1123. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5544332211) Running State Change CS_HANGUP (Cur 4 Tot 788)
1124. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:844 (sofia/default/5544332211) Callstate Change DOWN -> HANGUP
1125. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5544332211) State HANGUP
1126. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_sofia.c:468 Channel sofia/default/5544332211 hanging up, cause: NORMAL_TEMPORARY_FAILURE
1127. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:59 sofia/default/5544332211 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
1128. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5544332211) State HANGUP going to sleep
1129. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:616 (sofia/default/5544332211) State Change CS_HANGUP -> CS_REPORTING
1130. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5544332211) Running State Change CS_REPORTING (Cur 4 Tot 788)
1131. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5544332211) State REPORTING
1132. 2024-01-22 09:05:14.358576 33.57% [INFO] mod_json_cdr.c:272 Process [3d1eae89-a256-40b3-99da-9135639408d4.cdr.json]
1133. 2024-01-22 09:05:14.358576 33.57% [DEBUG] switch_ivr_originate.c:4045 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
1134. 2024-01-22 09:05:14.358576 33.57% [INFO] mod_dptools.c:3637 Originate Failed.  Cause: NORMAL_TEMPORARY_FAILURE
1135. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_name=8999)
1136. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_name]=[8999]
1137. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_caller_id_number=8999)
1138. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_caller_id_number]=[8999]
1139. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(calltype=STANDARD)
1140. 2024-01-22 09:05:14.358576 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [calltype]=[STANDARD]
1141. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(termination_rates=ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9)
1142. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [termination_rates]=[ID:27|CODE:^55.*|DESTINATION:|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.00000|INC:1|INITIALBLOCK:0|TRUNK:7|PROVIDER:9]                                                                                                                                                                    
1143. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(trunk_id=7)
1144. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [trunk_id]=[7]
1145. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(provider_id=9)
1146. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [provider_id]=[9]
1147. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(rate_flag=0)
1148. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [rate_flag]=[0]
1149. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_credit_limit=0.00000)
1150. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_credit_limit]=[0.00000]
1151. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_credit_limit=0.00000)
1152. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_credit_limit]=[0.00000]
1153. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(daily_call_used_limit=0.00000)
1154. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [daily_call_used_limit]=[0.00000]
1155. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(monthly_call_used_limit=0.00000)
1156. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [monthly_call_used_limit]=[0.00000]
1157. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(quality_trunk_seq=1,7)
1158. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [quality_trunk_seq]=[1,7]
1159. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(force_trunk_flag=0)
1160. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [force_trunk_flag]=[0]
1161. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=trunk_id=7)
1162. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[trunk_id=7]
1163. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(intcall=0)
1164. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [intcall]=[0]
1165. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_id=5544332211)
1166. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_id]=[5544332211]
1167. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(presence_data=x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46)
1168. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||Tushar M(6081200124)|||^55.* //  // 0.00000 |||Testsipp // ^55.* //  // 0.00000 // trunk_id=7|||STD|||103.240.35.46]                                                                                                                                                                                                        
1169. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
1170. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
1171. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org export(sip_h_X-ASTPP-Stir-Verification=Not Applicable)
1172. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_h_X-ASTPP-Stir-Verification]=[Not Applicable]
1173. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(stir_status=0)
1174. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [stir_status]=[0]
1175. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(sip_stir_shaken_attest=NA)
1176. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [sip_stir_shaken_attest]=[NA]
1177. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org limit(db Dell gw_Dell 1 )
1178. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_limit.c:124 incr called: Dell_gw_Dell max:1, interval:0
1179. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_db.c:194 Usage for Dell_gw_Dell is now 2/1
1180. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_ivr.c:2288 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_EXECUTE -> CS_ROUTING
1181. 2024-01-22 09:05:14.378582 33.57% [NOTICE] switch_ivr.c:2295 Transfer sofia/default/8999@astpplight.astppbilling.org to XML[limit_exceeded@default]
1182. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE going to sleep
1183. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_ROUTING (Cur 4 Tot 788)
1184. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING
1185. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_sofia.c:149 Call appears to be already acknowledged
1186. 2024-01-22 09:05:14.378582 33.57% [DEBUG] mod_sofia.c:158 sofia/default/8999@astpplight.astppbilling.org SOFIA ROUTING
1187. 2024-01-22 09:05:14.378582 33.57% [DEBUG] switch_core_state_machine.c:230 sofia/default/8999@astpplight.astppbilling.org Standard ROUTING
1188. 2024-01-22 09:05:14.378582 33.57% [INFO] mod_dialplan_xml.c:639 Processing 8999 <8999>->limit_exceeded in context default
1189. 2024-01-22 09:05:14.398581 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
1190. 2024-01-22 09:05:14.398581 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
1191. 2024-01-22 09:05:14.398581 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
1192. 2024-01-22 09:05:14.398581 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1193. 2024-01-22 09:05:14.418588 33.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : limit_exceeded
1194. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Generated XML:
1195. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
1196. <document type="freeswitch/xml">                                                                                                                                                                                  
1197. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
1198. <context name="default">                                                                                                                                                                                          
1199. <extension name="limit_exceeded">                                                                                                                                                                                  
1200. <condition field="destination_number" expression="^limit_exceeded$">                                                                                                                                              
1201. <action application="set" data="effective_destination_number=5544332211"/>                                                                                                                                        
1202. <action application="playback" data="/usr/local/freeswitch/sounds/en/us/callie/astpp-badnumber.wav"/>                                                                                                              
1203. <action application="hangup" data="CHANNEL_UNACCEPTABLE"/>                                                                                                                                                        
1204. </condition>                                                                                                                                                                                                      
1205. </extension>                                                                                                                                                                                                      
1206. </context>                                                                                                                                                                                                        
1207. </section>                                                                                                                                                                                                        
1208. </document>                                                                                                                                                                                                        
1209. Dialplan: sofia/default/8999@astpplight.astppbilling.org parsing [default->limit_exceeded] continue=false
1210. Dialplan: sofia/default/8999@astpplight.astppbilling.org Regex (PASS) [limit_exceeded] destination_number(limit_exceeded) =~ /^limit_exceeded$/ break=on-false
1211. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action set(effective_destination_number=5544332211)
1212. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action playback(/usr/local/freeswitch/sounds/en/us/callie/astpp-badnumber.wav)
1213. Dialplan: sofia/default/8999@astpplight.astppbilling.org Action hangup(CHANNEL_UNACCEPTABLE)
1214. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_state_machine.c:281 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_ROUTING -> CS_EXECUTE
1215. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/8999@astpplight.astppbilling.org) State ROUTING going to sleep
1216. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_EXECUTE (Cur 4 Tot 788)
1217. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE
1218. 2024-01-22 09:05:14.418588 33.57% [DEBUG] mod_sofia.c:213 sofia/default/8999@astpplight.astppbilling.org SOFIA EXECUTE
1219. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_state_machine.c:323 sofia/default/8999@astpplight.astppbilling.org Standard EXECUTE
1220. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org set(effective_destination_number=5544332211)
1221. 2024-01-22 09:05:14.418588 33.57% [DEBUG] mod_dptools.c:1673 SET sofia/default/8999@astpplight.astppbilling.org [effective_destination_number]=[5544332211]
1222. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_session.c:2785 Application playback Requires media! pre_answering channel sofia/default/8999@astpplight.astppbilling.org
1223. 2024-01-22 09:05:14.418588 33.57% [INFO] switch_core_session.c:2787 Sending early media
1224. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_media.c:8651 AUDIO RTP [sofia/default/8999@astpplight.astppbilling.org] 95.217.215.250 port 19692 -> 103.240.35.46 port 8002 codec: 8 ms: 20
1225. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_rtp.c:4377 Starting timer [soft] 160 bytes per 20ms
1226. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_media.c:8963 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf send payload to 101
1227. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_media.c:8970 sofia/default/8999@astpplight.astppbilling.org Set 2833 dtmf receive payload to 101
1228. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_media.c:8993 sofia/default/8999@astpplight.astppbilling.org Set rtp dtmf delay to 40
1229. 2024-01-22 09:05:14.418588 33.57% [DEBUG] mod_sofia.c:2609 Ring SDP:
1230. v=0                                                                                                                                                                                                                
1231. o=FreeSWITCH 1705894622 1705894623 IN IP4 95.217.215.250                                                                                                                                                          
1232. s=FreeSWITCH                                                                                                                                                                                                      
1233. c=IN IP4 95.217.215.250                                                                                                                                                                                            
1234. t=0 0                                                                                                                                                                                                              
1235. m=audio 19692 RTP/AVP 8 101                                                                                                                                                                                        
1236. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1237. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1238. a=fmtp:101 0-15                                                                                                                                                                                                    
1239. a=ptime:20                                                                                                                                                                                                        
1240. a=sendrecv                                                                                                                                                                                                        
1241.  
1242. 2024-01-22 09:05:14.418588 33.57% [NOTICE] mod_sofia.c:2612 Pre-Answer sofia/default/8999@astpplight.astppbilling.org!
1243. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_channel.c:3566 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change RINGING -> EARLY
1244. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org playback(/usr/local/freeswitch/sounds/en/us/callie/astpp-badnumber.wav)
1245. 2024-01-22 09:05:14.418588 33.57% [DEBUG] sofia.c:7487 Channel sofia/default/8999@astpplight.astppbilling.org entering state [early][183]
1246. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-badnumber.wav sample rate 16000 doesn't match requested rate 8000
1247. 2024-01-22 09:05:14.418588 33.57% [DEBUG] switch_ivr_play_say.c:1566 Codec Activated L16@8000hz 1 channels 20ms
1248. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:168 sofia/default/5544332211 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE
1249. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5544332211) State REPORTING going to sleep
1250. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:607 (sofia/default/5544332211) State Change CS_REPORTING -> CS_DESTROY
1251. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_session.c:1743 Session 788 (sofia/default/5544332211) Locked, Waiting on external entities
1252. 2024-01-22 09:05:14.698573 33.57% [NOTICE] switch_core_session.c:1761 Session 788 (sofia/default/5544332211) Ended
1253. 2024-01-22 09:05:14.698573 33.57% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/5544332211 [CS_DESTROY]
1254. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:735 (sofia/default/5544332211) Running State Change CS_DESTROY (Cur 3 Tot 788)
1255. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5544332211) State DESTROY
1256. 2024-01-22 09:05:14.698573 33.57% [DEBUG] mod_sofia.c:379 sofia/default/5544332211 SOFIA DESTROY
1257. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:175 sofia/default/5544332211 Standard DESTROY
1258. 2024-01-22 09:05:14.698573 33.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5544332211) State DESTROY going to sleep
1259. 2024-01-22 09:05:14.818579 33.57% [DEBUG] switch_rtp.c:7511 Correct audio ip/port confirmed.
1260. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_ivr_play_say.c:2015 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-badnumber.wav
1261. EXECUTE [depth=0] sofia/default/8999@astpplight.astppbilling.org hangup(CHANNEL_UNACCEPTABLE)
1262. 2024-01-22 09:05:16.478584 33.53% [NOTICE] mod_dptools.c:1376 Hangup sofia/default/8999@astpplight.astppbilling.org [CS_EXECUTE] [CHANNEL_UNACCEPTABLE]
1263. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_session.c:2973 sofia/default/8999@astpplight.astppbilling.org skip receive message [PHONE_EVENT] (channel is hungup already)
1264. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE going to sleep
1265. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_HANGUP (Cur 3 Tot 788)
1266. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:844 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change EARLY -> HANGUP
1267. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8999@astpplight.astppbilling.org) State HANGUP
1268. 2024-01-22 09:05:16.478584 33.53% [DEBUG] mod_sofia.c:462 sofia/default/8999@astpplight.astppbilling.org Overriding SIP cause 480 with 503 from the other leg
1269. 2024-01-22 09:05:16.478584 33.53% [DEBUG] mod_sofia.c:468 Channel sofia/default/8999@astpplight.astppbilling.org hanging up, cause: CHANNEL_UNACCEPTABLE
1270. 2024-01-22 09:05:16.478584 33.53% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 503
1271. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:59 sofia/default/8999@astpplight.astppbilling.org Standard HANGUP, cause: CHANNEL_UNACCEPTABLE
1272. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8999@astpplight.astppbilling.org) State HANGUP going to sleep
1273. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:616 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_HANGUP -> CS_REPORTING
1274. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_REPORTING (Cur 3 Tot 788)
1275. 2024-01-22 09:05:16.478584 33.53% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8999@astpplight.astppbilling.org) State REPORTING
1276. 2024-01-22 09:05:16.478584 33.53% [INFO] mod_json_cdr.c:272 Process [2c6f4f06-dfa2-4f26-9ae6-de750ade17f6.cdr.json]
1277. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:168 sofia/default/8999@astpplight.astppbilling.org Standard REPORTING, cause: CHANNEL_UNACCEPTABLE
1278. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8999@astpplight.astppbilling.org) State REPORTING going to sleep
1279. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:607 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_REPORTING -> CS_DESTROY
1280. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_session.c:1743 Session 787 (sofia/default/8999@astpplight.astppbilling.org) Locked, Waiting on external entities
1281. 2024-01-22 09:05:16.518575 33.53% [NOTICE] switch_core_session.c:1761 Session 787 (sofia/default/8999@astpplight.astppbilling.org) Ended
1282. 2024-01-22 09:05:16.518575 33.53% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/8999@astpplight.astppbilling.org [CS_DESTROY]
1283. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:735 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_DESTROY (Cur 2 Tot 788)
1284. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8999@astpplight.astppbilling.org) State DESTROY
1285. 2024-01-22 09:05:16.518575 33.53% [DEBUG] mod_sofia.c:379 sofia/default/8999@astpplight.astppbilling.org SOFIA DESTROY
1286. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:175 sofia/default/8999@astpplight.astppbilling.org Standard DESTROY
1287. 2024-01-22 09:05:16.518575 33.53% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8999@astpplight.astppbilling.org) State DESTROY going to sleep
1288. 2024-01-22 09:05:20.258587 32.43% [DEBUG] switch_channel.c:2160 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change HELD -> UNHELD
1289. 2024-01-22 09:05:20.258587 32.43% [NOTICE] sofia.c:1065 Hangup sofia/default/8999@astpplight.astppbilling.org [CS_EXECUTE] [NORMAL_CLEARING]
1290. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/8999@astpplight.astppbilling.org]
1291. 2024-01-22 09:05:20.278615 32.43% [NOTICE] switch_ivr_bridge.c:926 Hangup sofia/default/5566778899 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1292. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/5566778899 skip receive message [UNBRIDGE] (channel is hungup already)
1293. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/8999@astpplight.astppbilling.org skip receive message [UNBRIDGE] (channel is hungup already)
1294. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_session.c:2973 sofia/default/8999@astpplight.astppbilling.org skip receive message [PHONE_EVENT] (channel is hungup already)
1295. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:647 (sofia/default/8999@astpplight.astppbilling.org) State EXECUTE going to sleep
1296. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_HANGUP (Cur 2 Tot 788)
1297. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:844 (sofia/default/8999@astpplight.astppbilling.org) Callstate Change UNHELD -> HANGUP
1298. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8999@astpplight.astppbilling.org) State HANGUP
1299. 2024-01-22 09:05:20.278615 32.43% [DEBUG] mod_sofia.c:462 sofia/default/8999@astpplight.astppbilling.org Overriding SIP cause 480 with 503 from the other leg
1300. 2024-01-22 09:05:20.278615 32.43% [DEBUG] mod_sofia.c:468 Channel sofia/default/8999@astpplight.astppbilling.org hanging up, cause: NORMAL_CLEARING
1301. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:59 sofia/default/8999@astpplight.astppbilling.org Standard HANGUP, cause: NORMAL_CLEARING
1302. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/8999@astpplight.astppbilling.org) State HANGUP going to sleep
1303. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:616 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_HANGUP -> CS_REPORTING
1304. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_REPORTING (Cur 2 Tot 788)
1305. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8999@astpplight.astppbilling.org) State REPORTING
1306. 2024-01-22 09:05:20.278615 32.43% [INFO] mod_json_cdr.c:272 Process [01bbc876-3a29-4c0a-a872-f3354dbcb114.cdr.json]
1307. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_play_say.c:2015 done playing file local_stream://moh
1308. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_session.c:2973 sofia/default/5566778899 skip receive message [PHONE_EVENT] (channel is hungup already)
1309. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:567 sofia/default/5566778899 skip receive message [BRIDGE] (channel is hungup already)
1310. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr.c:679 sofia/default/5566778899 skip receive message [VIDEO_SYNC] (channel is hungup already)
1311. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:825 sofia/default/5566778899 ending bridge by request from read function
1312. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/5566778899]
1313. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:650 (sofia/default/5566778899) State EXCHANGE_MEDIA going to sleep
1314. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_HANGUP (Cur 2 Tot 788)
1315. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:844 (sofia/default/5566778899) Callstate Change ACTIVE -> HANGUP
1316. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5566778899) State HANGUP
1317. 2024-01-22 09:05:20.278615 32.43% [DEBUG] mod_sofia.c:462 sofia/default/5566778899 Overriding SIP cause 480 with 200 from the other leg
1318. 2024-01-22 09:05:20.278615 32.43% [DEBUG] mod_sofia.c:468 Channel sofia/default/5566778899 hanging up, cause: NORMAL_CLEARING
1319. 2024-01-22 09:05:20.278615 32.43% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/5566778899
1320. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:59 sofia/default/5566778899 Standard HANGUP, cause: NORMAL_CLEARING
1321. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:846 (sofia/default/5566778899) State HANGUP going to sleep
1322. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:616 (sofia/default/5566778899) State Change CS_HANGUP -> CS_REPORTING
1323. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:581 (sofia/default/5566778899) Running State Change CS_REPORTING (Cur 2 Tot 788)
1324. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5566778899) State REPORTING
1325. 2024-01-22 09:05:20.278615 32.43% [INFO] mod_json_cdr.c:272 Process [bbaaa2da-014b-407d-b118-9fdebbfd2320.cdr.json]
1326. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/5566778899 Standard REPORTING, cause: NORMAL_CLEARING
1327. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/5566778899) State REPORTING going to sleep
1328. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/5566778899) State Change CS_REPORTING -> CS_DESTROY
1329. 2024-01-22 09:05:20.278615 32.43% [DEBUG] switch_core_session.c:1743 Session 786 (sofia/default/5566778899) Locked, Waiting on external entities
1330. 2024-01-22 09:05:20.278615 32.43% [NOTICE] switch_core_session.c:1761 Session 786 (sofia/default/5566778899) Ended
1331. 2024-01-22 09:05:20.278615 32.43% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/5566778899 [CS_DESTROY]
1332. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/5566778899) Running State Change CS_DESTROY (Cur 1 Tot 788)
1333. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5566778899) State DESTROY
1334. 2024-01-22 09:05:20.298575 32.43% [DEBUG] mod_sofia.c:379 sofia/default/5566778899 SOFIA DESTROY
1335. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/5566778899 Standard DESTROY
1336. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/5566778899) State DESTROY going to sleep
1337. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:168 sofia/default/8999@astpplight.astppbilling.org Standard REPORTING, cause: NORMAL_CLEARING
1338. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:932 (sofia/default/8999@astpplight.astppbilling.org) State REPORTING going to sleep
1339. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:607 (sofia/default/8999@astpplight.astppbilling.org) State Change CS_REPORTING -> CS_DESTROY
1340. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_session.c:1743 Session 784 (sofia/default/8999@astpplight.astppbilling.org) Locked, Waiting on external entities
1341. 2024-01-22 09:05:20.298575 32.43% [NOTICE] switch_core_session.c:1761 Session 784 (sofia/default/8999@astpplight.astppbilling.org) Ended
1342. 2024-01-22 09:05:20.298575 32.43% [NOTICE] switch_core_session.c:1765 Close Channel sofia/default/8999@astpplight.astppbilling.org [CS_DESTROY]
1343. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:735 (sofia/default/8999@astpplight.astppbilling.org) Running State Change CS_DESTROY (Cur 0 Tot 788)
1344. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8999@astpplight.astppbilling.org) State DESTROY
1345. 2024-01-22 09:05:20.298575 32.43% [DEBUG] mod_sofia.c:379 sofia/default/8999@astpplight.astppbilling.org SOFIA DESTROY
1346. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:175 sofia/default/8999@astpplight.astppbilling.org Standard DESTROY
1347. 2024-01-22 09:05:20.298575 32.43% [DEBUG] switch_core_state_machine.c:745 (sofia/default/8999@astpplight.astppbilling.org) State DESTROY going to sleep
1348. 2024-01-22 09:05:20.778581 32.43% [DEBUG] switch_scheduler.c:147 Deleting task 9 switch_ivr_schedule_hangup (bbaaa2da-014b-407d-b118-9fdebbfd2320)
1349.