1. Type /help <enter> to see a list of commands
2.  
3.  
4.  
5. +OK log level  [7]
6. freeswitch@devangnewdeb11>
7. freeswitch@devangnewdeb11>
8. 2022-07-12 11:16:09.776125 51.00% [NOTICE] switch_channel.c:1123 New Channel sofia/default/4010463821@alpha.astppbilling.org:5060 [4d42da28-1d6b-4aa4-9226-07206ed77457]
9. 2022-07-12 11:16:09.776125 51.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_NEW (Cur 1 Tot 380)
10. 2022-07-12 11:16:09.776125 51.00% [INFO] sofia.c:10462 sofia/default/4010463821@alpha.astppbilling.org:5060 receiving invite from 103.240.35.46:5060 version: 1.10.7 -release 64bit call-id: tusyhpvsqtxaibh@pk-qa
11. 2022-07-12 11:16:09.776125 51.00% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
12. 2022-07-12 11:16:09.776125 51.00% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'default' for [107770@alpha.astppbilling.org] from ip 103.240.35.46
13. 2022-07-12 11:16:09.776125 51.00% [DEBUG] switch_core_state_machine.c:600 (sofia/default/4010463821@alpha.astppbilling.org:5060) State NEW
14. 2022-07-12 11:16:09.776125 51.00% [DEBUG] sofia.c:2419 detaching session 4d42da28-1d6b-4aa4-9226-07206ed77457
15. 2022-07-12 11:16:09.956106 51.00% [DEBUG] sofia.c:2532 Re-attaching to session 4d42da28-1d6b-4aa4-9226-07206ed77457
16. 2022-07-12 11:16:09.956106 51.00% [INFO] sofia.c:10462 sofia/default/4010463821@alpha.astppbilling.org:5060 receiving invite from 103.240.35.46:5060 version: 1.10.7 -release 64bit call-id: tusyhpvsqtxaibh@pk-qa
17. 2022-07-12 11:16:09.956106 51.00% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 103.240.35.46:0.
18. 2022-07-12 11:16:09.976083 51.00% [DEBUG] sofia.c:7499 Channel sofia/default/4010463821@alpha.astppbilling.org:5060 entering state [received][100]
19. 2022-07-12 11:16:09.976083 51.00% [DEBUG] sofia.c:7509 Remote SDP:
20. v=0
21. o=twinkle 775875448 994624259 IN IP4 103.240.35.46
22. s=-
23. c=IN IP4 103.240.35.46
24. t=0 0
25. m=audio 8000 RTP/AVP 98 97 8 0 3 101
26. a=rtpmap:98 speex/16000
27. a=rtpmap:97 speex/8000
28. a=rtpmap:8 PCMA/8000
29. a=rtpmap:0 PCMU/8000
30. a=rtpmap:3 GSM/8000
31. a=rtpmap:101 telephone-event/8000
32. a=fmtp:101 0-15
33. a=ptime:20
34.  
35. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
36. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:98:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
37. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:97:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
38. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:97:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
39. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
40. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
41. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
42. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
43. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
44. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
45. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
46. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
47. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
48. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/4010463821@alpha.astppbilling.org:5060 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
49. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_codec.c:111 sofia/default/4010463821@alpha.astppbilling.org:5060 Original read codec set to PCMA:8
50. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
51. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_media.c:5973 sofia/default/4010463821@alpha.astppbilling.org:5060 Set 2833 dtmf send payload to 101 recv payload to 101
52. 2022-07-12 11:16:09.976083 51.00% [DEBUG] sofia.c:7933 (sofia/default/4010463821@alpha.astppbilling.org:5060) State Change CS_NEW -> CS_INIT
53. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_INIT (Cur 1 Tot 380)
54. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4010463821@alpha.astppbilling.org:5060) State INIT
55. 2022-07-12 11:16:09.976083 51.00% [DEBUG] mod_sofia.c:97 sofia/default/4010463821@alpha.astppbilling.org:5060 SOFIA INIT
56. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:40 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard INIT
57. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:48 (sofia/default/4010463821@alpha.astppbilling.org:5060) State Change CS_INIT -> CS_ROUTING
58. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:624 (sofia/default/4010463821@alpha.astppbilling.org:5060) State INIT going to sleep
59. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_ROUTING (Cur 1 Tot 380)
60. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_channel.c:2380 (sofia/default/4010463821@alpha.astppbilling.org:5060) Callstate Change DOWN -> RINGING
61. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4010463821@alpha.astppbilling.org:5060) State ROUTING
62. 2022-07-12 11:16:09.976083 51.00% [DEBUG] mod_sofia.c:158 sofia/default/4010463821@alpha.astppbilling.org:5060 SOFIA ROUTING
63. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_core_state_machine.c:230 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard ROUTING
64. 2022-07-12 11:16:09.976083 51.00% [INFO] mod_dialplan_xml.c:639 Processing 4010463821 <4010463821>->107770 in context default
65. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] USING CACHE
66. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
67. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
68. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
69. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
70. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 107770
71. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_code = '107770' limit 1
72. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_ACCESS_NUMBER]1.0
73. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [Fifo Condition Check] Query :SELECT id,destination_number,agent_hold_file from pbx_queue WHERE destination_number = '107770' and status=0 LIMIT 1
74. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBER4010463821
75. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
76. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '107770' limit 1
77. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 4010463821 / 4010463821
78. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Interconnect code destination number
79. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_interconnectcode_info] Query :SELECT * FROM interconnectcode WHERE interconnectcode = 107770 AND status=0 limit 1
80. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
81. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
82. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
83. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="107770" LIMIT 1
84. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
85. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
86. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 4010463821]
87. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "4010463821" AND deleted = 0 limit 1
88. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
89. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]0
90. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DID]1
91. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Call Direction : inbound
92. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] DID ACCOUNT ID : 49
93. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^107770.*' OR patterns = '^10777.*' OR patterns = '^1077.*' OR patterns = '^107.*' OR patterns = '^10.*' OR patterns = '^1.*' OR patterns ='--') AND accountid = 49 ORDER BY LENGTH(PKGPTR.patterns) DESC
94. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="107770" AND A.accountid = '49' limit 1
95. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 107770
96. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]0.0
97. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1
98. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_DIRECTION]1.1 :: CALL IS DID
99. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="107770" LIMIT 1
100. 2022-07-12 11:16:09.976083 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
101. 2022-07-12 11:16:09.976083 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
102. 2022-07-12 11:16:09.996087 51.00% [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 = '107770' OR number = '10777' OR number = '1077' OR number = '107' OR number = '10' OR number = '1' OR number ='--') OR (number = '107770*' OR number = '10777*' OR number = '1077*' OR number = '107*' OR number = '10*' OR number = '1*' OR number ='--') OR (number = '4010463821' OR number = '401046382' OR number = '40104638' OR number = '4010463' OR number = '401046' OR number = '40104' OR number = '4010' OR number = '401' OR number = '40' OR number = '4' OR number ='--') OR (number = '4010463821*' OR number = '401046382*' OR number = '40104638*' OR number = '4010463*' OR number = '401046*' OR number = '40104*' OR number = '4010*' OR number = '401*' OR number = '40*' OR number = '4*' OR number ='--')) AND status=0 AND accountid = 0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
103. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
104. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '107770' OR routing_prefix = '10777' OR routing_prefix = '1077' OR routing_prefix = '107' OR routing_prefix = '10' OR routing_prefix = '1' OR routing_prefix ='--') AND status = 0 and reseller_id = 48 limit 1
105. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
106. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 49
107. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 4010463821
108. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
109. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 39993.999
110. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
111. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 15
112. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
113. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
114. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
115. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::15
116. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
117. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="107770" LIMIT 1
118. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
119. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
120. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
121. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15
122. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
123. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
124. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
125. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 107770
126. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 88
127. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 49
128. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
129. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
130. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your39993.999 balance Accountid 49 !!!
131. 2022-07-12 11:16:09.996087 51.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
132. 2022-07-12 11:16:09.996087 51.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] FINDING LIMIT FOR RESELLER: 48
133. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "48" AND deleted = 0 limit 1
134. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
135. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Reseller Information ===================
136. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 48
137. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9924732339
138. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
139. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 39993.999
140. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
141. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 1
142. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.0
143. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_PRICELIST]1.1:CACHING ID:::1
144. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :inbound
145. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="107770" LIMIT 1
146. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
147. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
148. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
149. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15
150. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
151. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
152. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
153. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 107770
154. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 88
155. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 48
156. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
157. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
158. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your39993.999 balance Accountid 48 !!!
159. 2022-07-12 11:16:09.996087 51.00% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 100
160. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Reseller xml_user_rates : ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49||ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49
161. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
162. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Lossless Routing Status : 1
163. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
164. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
165. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
166. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 49 AND status=0 LIMIT 1
167. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE id = "49" AND deleted = 0 limit 1
168. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Ashish account call balance :SELECT * FROM accounts WHERE id =49
169. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:49
170. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:49
171. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 49
172. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 49 limit 1
173. 2022-07-12 11:16:09.996087 51.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : alpha.astppbilling.org
174. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
175. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
176. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
177. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 49 limit 1
178. 2022-07-12 11:16:09.996087 51.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : alpha.astppbilling.org
179. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_accounts_info] Query :SELECT * FROM accounts WHERE id = 49  and status = 0 limit 1
180. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [XML] Custom Function Name :: custom_inbound_7
181. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : 7
182. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : Ring Group
183. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Query - SELECT call_screening from accounts WHERE id = '49' LIMIT 1
184. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Screening Option - 0
185. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Query - SELECT value from `system` WHERE name = 'code_welcome_file' LIMIT 1
186. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : Screening Option - astpp-screen.wav
187. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : is_intro_recorded - true
188. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : introduction_recording_name - 4010463821-20220712111610.wav
189. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET RING GROUP INFO] Query :SELECT id as ringgroup_id,name as ringgroup_name,strategy as ringgroup_strategy,destinations as ringgroup_destinations,description as ringgroup_description,announcementid as ringgroup_announcementid,call_record as ringgroup_call_record,skip_busy as ringgroup_skip_busy,ringbackid as ringgroup_ringbackid,calleridname as ringgroup_calleridname,greetingid as ringgroup_greetingid,calleridnumber as ringgroup_calleridnumber,no_answer as ringgroup_no_answer,no_answer_call_type as no_answer_call_type,voicemail_set,no_answer_call_type_value, greetingid as ringgroup_greetingid, music_on_hold_type,music_on_hold from pbx_ringgroup WHERE id = 1 and status = 0 LIMIT 1
190. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX]  Conference greetingid::1
191. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '1' LIMIT 1
192. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== music_file_name=File name==================taunt_.wav
193. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET MOH] Query :SELECT name FROM pbx_music_on_hold WHERE id = 1 ORDER BY RAND() LIMIT 1
194. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] music on hold name :- MusicOnHold
195. 2022-07-12 11:16:09.996087 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_domain_info] Query :SELECT * FROM domains WHERE accountid = 49 limit 1
196. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : introduction_recording_name = 4010463821-20220712111610.wav
197. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : voicemail_sip =
198. 2022-07-12 11:16:09.996087 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] did_pbx_info : voicemail_sip_domain = alpha.astppbilling.org
199. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Final Count : 0===
200. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC1]
201. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="2357506173"  AND domains.domain="alpha.astppbilling.org" limit 1
202. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_extensions_forwarding] Query :select sip_devices.id,sip_device_routing.call_forwarding_flag,sip_device_routing.call_forwarding_destination from sip_devices JOIN sip_device_routing ON sip_devices.id = sip_device_routing.sip_device_id where sip_devices.username = "2357506173"
203. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC3]
204. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="2357506173"  AND domains.domain="alpha.astppbilling.org" limit 1
205. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Final Count : 0===
206. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC1]
207. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="3937482392"  AND domains.domain="alpha.astppbilling.org" limit 1
208. 2022-07-12 11:16:10.016098 51.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] sip_codec : PCMA,PCMU
209. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_extensions_forwarding] Query :select sip_devices.id,sip_device_routing.call_forwarding_flag,sip_device_routing.call_forwarding_destination from sip_devices JOIN sip_device_routing ON sip_devices.id = sip_device_routing.sip_device_id where sip_devices.username = "3937482392"
210. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC3]
211. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="3937482392"  AND domains.domain="alpha.astppbilling.org" limit 1
212. 2022-07-12 11:16:10.016098 51.00% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] sip_codec : PCMA,PCMU
213. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] Final Count : 0===
214. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC1]
215. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1190246984"  AND domains.domain="alpha.astppbilling.org" limit 1
216. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_extensions_forwarding] Query :select sip_devices.id,sip_device_routing.call_forwarding_flag,sip_device_routing.call_forwarding_destination from sip_devices JOIN sip_device_routing ON sip_devices.id = sip_device_routing.sip_device_id where sip_devices.username = "1190246984"
217. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC3]
218. 2022-07-12 11:16:10.016098 51.00% [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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1190246984"  AND domains.domain="alpha.astppbilling.org" limit 1
219. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Recording Flag : 0
220. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_cpp.cpp:1465 [ASTPP] RingGroup Dlr Str : [sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=40,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=2357506173,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=40,leg_delay_start=0]user/2357506173@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=55,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=3937482392,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=55,leg_delay_start=0,absolute_codec_string=^^:PCMA:PCMU]user/3937482392@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=50,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=1190246984,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=50,leg_delay_start=0]user/1190246984@alpha.astppbilling.org,
221. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Functions] [GET_RING_GROUP_INFO] Query :SELECT file_name from pbx_recording WHERE id = '' LIMIT 1
222. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer_attended' limit 1
223. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_FEATURE_CODE] Query :SELECT id,feature_action,feature_code from feature_code where feature_action = 'call_transfer' limit 1
224. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:<?xml version="1.0" encoding="UTF-8" standalone="no"?>
225. <document type="freeswitch/xml">
226. <section name="dialplan" description="ASTPP Dialplan">
227. <context name="default">
228. <extension name="107770">
229. <condition field="destination_number" expression="107770">
230. <action application="set" data="effective_destination_number=107770"/>
231. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
232. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>
233. <action application="set" data="notify_flag=0"/>
234. <action application="set" data="notify_credit_limit=5.00000"/>
235. <action application="set" data="type=0"/>
236. <action application="set" data="callstart=2022-07-12 11:16:10"/>
237. <action application="set" data="hangup_after_bridge=true"/>
238. <action application="set" data="continue_on_fail=TRUE"/>
239. <action application="set" data="account_id=49"/>
240. <action application="set" data="parent_id=48"/>
241. <action application="set" data="entity_id=0"/>
242. <action application="set" data="call_processed=internal"/>
243. <action application="set" data="call_direction=inbound"/>
244. <action application="set" data="accountname=default"/>
245. <action application="set" data="origination_rates_did=ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49||ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49"/>
246. <action application="set" data="origination_rates=0"/>
247. <action application="set" data="original_caller_id_name=4010463821"/>
248. <action application="set" data="original_caller_id_number=4010463821"/>
249. <action application="set" data="effective_caller_id_name=4010463821"/>
250. <action application="set" data="effective_caller_id_number=4010463821"/>
251. <action application="set" data="receiver_accid=49"/>
252. <action application="export" data="domain_name=alpha.astppbilling.org"/>
253. <action application="export" data="sip_to_host=alpha.astppbilling.org"/>
254. <action application="export" data="presence_data=x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46"/>
255. <action application="export" data="call_type=7"/>
256. <action application="set" data="did_calltype=7"/>
257. <action application="set" data="did_extensions=1"/>
258. <action application="playback" data="/var/www/html/astpp/upload/astpp-screen.wav"/>
259. <action application="set" data="playback_terminators=#"/>
260. <action application="record" data="/var/www/html/astpp/upload/callscreening/4010463821-20220712111610.wav 20 200"/>
261. <action application="playback" data="/var/www/html/astpp//upload/pbx/taunt_.wav"/>
262. <action application="bridge_export" data="hold_music=local_stream://MusicOnHold"/>
263. <action application="set" data="group_confirm_key=exec"/>
264. <action application="set" data="group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua"/>
265. <action application="export" data="is_recording=1"/>
266. <action application="export" data="media_bug_answer_req=true"/>
267. <action application="export" data="RECORD_STEREO=true"/>
268. <action application="export" data="record_sample_rate=8000"/>
269. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>
270. <action application="set" data="ringback="/>
271. <action application="set" data="module_name=pbx"/>
272. <action application="db" data="insert/pickup_any_group_${callgroup}/1/${uuid}"/>
273. <action application="set" data="dialed_extension=transfer_call"/>
274. <action application="export" data="dialed_extension=transfer_call"/>
275. <action application="bind_meta_app" data="2 b s execute_extension::transfer_call_2 XML default"/>
276. <action application="bind_meta_app" data="3 b s execute_extension::transfer_call_3 XML default"/>
277. <action application="export" data="ring_group_transfer=true"/>
278. <action application="set" data="fail_on_single_reject=CALL_REJECTED"/>
279. <action application="bridge" data="{sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=40,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=2357506173,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=40,leg_delay_start=0]user/2357506173@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=55,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=3937482392,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=55,leg_delay_start=0,absolute_codec_string=^^:PCMA:PCMU]user/3937482392@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=50,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=1190246984,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=50,leg_delay_start=0]user/1190246984@alpha.astppbilling.org,"/>
280. <action application="set" data="original_did_number=107770"/>
281. <action application="set" data="no_answer_number="/>
282. <action application="set" data="no_answer_call_type=extension"/>
283. <action application="set" data="no_answer_call_type_value=53"/>
284. <action application="set" data="variable_sip_to_host=alpha.astppbilling.org"/>
285. <action application="set" data="userinfo_id=49"/>
286. <action application="set" data="variable_sip_to_host=alpha.astppbilling.org"/>
287. <action application="set" data="opensips_flag=1"/>
288. <action application="lua" data="astpp/lib/pbx/astpp.ring_group_destination.lua"/>
289. </condition>
290. </extension>
291. </context>
292. </section>
293. </document>
294. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 parsing [default->107770] continue=false
295. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Regex (PASS) [107770] destination_number(107770) =~ /107770/ break=on-false
296. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(effective_destination_number=107770)
297. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(bridge_pre_execute_bleg_app=sched_hangup)
298. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
299. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(notify_flag=0)
300. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(notify_credit_limit=5.00000)
301. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(type=0)
302. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(callstart=2022-07-12 11:16:10)
303. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(hangup_after_bridge=true)
304. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(continue_on_fail=TRUE)
305. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(account_id=49)
306. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(parent_id=48)
307. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(entity_id=0)
308. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(call_processed=internal)
309. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(call_direction=inbound)
310. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(accountname=default)
311. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(origination_rates_did=ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49||ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49)
312. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(origination_rates=0)
313. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(original_caller_id_name=4010463821)
314. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(original_caller_id_number=4010463821)
315. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(effective_caller_id_name=4010463821)
316. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(effective_caller_id_number=4010463821)
317. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(receiver_accid=49)
318. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(domain_name=alpha.astppbilling.org)
319. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(sip_to_host=alpha.astppbilling.org)
320. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(presence_data=x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46)
321. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(call_type=7)
322. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(did_calltype=7)
323. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(did_extensions=1)
324. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action playback(/var/www/html/astpp/upload/astpp-screen.wav)
325. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(playback_terminators=#)
326. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action record(/var/www/html/astpp/upload/callscreening/4010463821-20220712111610.wav 20 200)
327. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action playback(/var/www/html/astpp//upload/pbx/taunt_.wav)
328. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action bridge_export(hold_music=local_stream://MusicOnHold)
329. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(group_confirm_key=exec)
330. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
331. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(is_recording=1)
332. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(media_bug_answer_req=true)
333. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(RECORD_STEREO=true)
334. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(record_sample_rate=8000)
335. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
336. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(ringback=)
337. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(module_name=pbx)
338. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action db(insert/pickup_any_group_${callgroup}/1/${uuid})
339. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(dialed_extension=transfer_call)
340. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(dialed_extension=transfer_call)
341. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
342. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
343. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action export(ring_group_transfer=true)
344. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(fail_on_single_reject=CALL_REJECTED)
345. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action bridge({sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=40,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=2357506173,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=40,leg_delay_start=0]user/2357506173@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=55,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=3937482392,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=55,leg_delay_start=0,absolute_codec_string=^^:PCMA:PCMU]user/3937482392@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=50,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=1190246984,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=50,leg_delay_start=0]user/1190246984@alpha.astppbilling.org,)
346. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(original_did_number=107770)
347. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(no_answer_number=)
348. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(no_answer_call_type=extension)
349. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(no_answer_call_type_value=53)
350. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(variable_sip_to_host=alpha.astppbilling.org)
351. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(userinfo_id=49)
352. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(variable_sip_to_host=alpha.astppbilling.org)
353. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action set(opensips_flag=1)
354. Dialplan: sofia/default/4010463821@alpha.astppbilling.org:5060 Action lua(astpp/lib/pbx/astpp.ring_group_destination.lua)
355. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_state_machine.c:281 (sofia/default/4010463821@alpha.astppbilling.org:5060) State Change CS_ROUTING -> CS_EXECUTE
356. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_state_machine.c:640 (sofia/default/4010463821@alpha.astppbilling.org:5060) State ROUTING going to sleep
357. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_EXECUTE (Cur 1 Tot 380)
358. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4010463821@alpha.astppbilling.org:5060) State EXECUTE
359. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_sofia.c:213 sofia/default/4010463821@alpha.astppbilling.org:5060 SOFIA EXECUTE
360. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_state_machine.c:323 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard EXECUTE
361. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(effective_destination_number=107770)
362. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [effective_destination_number]=[107770]
363. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(bridge_pre_execute_bleg_app=sched_hangup)
364. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [bridge_pre_execute_bleg_app]=[sched_hangup]
365. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
366. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
367. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(notify_flag=0)
368. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [notify_flag]=[0]
369. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(notify_credit_limit=5.00000)
370. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [notify_credit_limit]=[5.00000]
371. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(type=0)
372. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [type]=[0]
373. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(callstart=2022-07-12 11:16:10)
374. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [callstart]=[2022-07-12 11:16:10]
375. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(hangup_after_bridge=true)
376. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [hangup_after_bridge]=[true]
377. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(continue_on_fail=TRUE)
378. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [continue_on_fail]=[TRUE]
379. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(account_id=49)
380. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [account_id]=[49]
381. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(parent_id=48)
382. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [parent_id]=[48]
383. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(entity_id=0)
384. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [entity_id]=[0]
385. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(call_processed=internal)
386. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [call_processed]=[internal]
387. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(call_direction=inbound)
388. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [call_direction]=[inbound]
389. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(accountname=default)
390. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [accountname]=[default]
391. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(origination_rates_did=ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49||ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49)
392. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [origination_rates_did]=[ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49||ID:15|CODE:^107770.*|DESTINATION:107770|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:7|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:88|ACCID:49]
393. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(origination_rates=0)
394. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [origination_rates]=[0]
395. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(original_caller_id_name=4010463821)
396. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [original_caller_id_name]=[4010463821]
397. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(original_caller_id_number=4010463821)
398. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [original_caller_id_number]=[4010463821]
399. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(effective_caller_id_name=4010463821)
400. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [effective_caller_id_name]=[4010463821]
401. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(effective_caller_id_number=4010463821)
402. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [effective_caller_id_number]=[4010463821]
403. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(receiver_accid=49)
404. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [receiver_accid]=[49]
405. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(domain_name=alpha.astppbilling.org)
406. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [domain_name]=[alpha.astppbilling.org]
407. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(sip_to_host=alpha.astppbilling.org)
408. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [sip_to_host]=[alpha.astppbilling.org]
409. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(presence_data=x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46)
410. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46]
411. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(call_type=7)
412. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [call_type]=[7]
413. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(did_calltype=7)
414. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [did_calltype]=[7]
415. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(did_extensions=1)
416. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [did_extensions]=[1]
417. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_session.c:2795 Application playback Requires media! pre_answering channel sofia/default/4010463821@alpha.astppbilling.org:5060
418. 2022-07-12 11:16:10.016098 51.00% [INFO] switch_core_session.c:2797 Sending early media
419. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/4010463821@alpha.astppbilling.org:5060] 168.119.48.166 port 19228 -> 103.240.35.46 port 8000 codec: 8 ms: 20
420. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
421. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_media.c:9089 sofia/default/4010463821@alpha.astppbilling.org:5060 Set 2833 dtmf send payload to 101
422. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_media.c:9096 sofia/default/4010463821@alpha.astppbilling.org:5060 Set 2833 dtmf receive payload to 101
423. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_media.c:9119 sofia/default/4010463821@alpha.astppbilling.org:5060 Set rtp dtmf delay to 40
424. 2022-07-12 11:16:10.016098 51.00% [DEBUG] mod_sofia.c:2610 Ring SDP:
425. v=0
426. o=FreeSWITCH 1657605342 1657605343 IN IP4 168.119.48.166
427. s=FreeSWITCH
428. c=IN IP4 168.119.48.166
429. t=0 0
430. m=audio 19228 RTP/AVP 8 101
431. a=rtpmap:8 PCMA/8000
432. a=rtpmap:101 telephone-event/8000
433. a=fmtp:101 0-15
434. a=ptime:20
435. a=sendrecv
436.  
437. 2022-07-12 11:16:10.016098 51.00% [NOTICE] mod_sofia.c:2613 Pre-Answer sofia/default/4010463821@alpha.astppbilling.org:5060!
438. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_channel.c:3622 (sofia/default/4010463821@alpha.astppbilling.org:5060) Callstate Change RINGING -> EARLY
439. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 playback(/var/www/html/astpp/upload/astpp-screen.wav)
440. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp/upload/astpp-screen.wav sample rate 24000 doesn't match requested rate 8000
441. 2022-07-12 11:16:10.016098 51.00% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
442. 2022-07-12 11:16:10.016098 51.00% [DEBUG] sofia.c:7499 Channel sofia/default/4010463821@alpha.astppbilling.org:5060 entering state [early][183]
443. 2022-07-12 11:16:10.236121 51.00% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
444. 2022-07-12 11:16:14.796100 62.20% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp/upload/astpp-screen.wav
445. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(playback_terminators=#)
446. span style="color: goldenrod; font-weight: bold;"> 2022-07-12 11:16:14.796100 62.20% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [playback_terminators]=[#]
447. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 record(/var/www/html/astpp/upload/callscreening/4010463821-20220712111610.wav 20 200)
448. 2022-07-12 11:16:14.796100 62.20% [DEBUG] switch_ivr_play_say.c:604 Raw Codec Activated, ready to waste resources!
449. 2022-07-12 11:16:14.796100 62.20% [DEBUG] switch_ivr_play_say.c:716 Raw Codec Activated
450. 2022-07-12 11:16:14.796100 62.20% [DEBUG] switch_core_codec.c:223 sofia/default/4010463821@alpha.astppbilling.org:5060 Push codec L16:100
451. 2022-07-12 11:16:17.456092 68.20% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF #:800
452. span style="color: green; font-weight: bold;"> 2022-07-12 11:16:17.456092 68.20% [INFO] switch_channel.c:527 RECV DTMF #:800
453. span style="color: goldenrod; font-weight: bold;"> 2022-07-12 11:16:17.456092 68.20% [DEBUG] mod_dptools.c:2389 Digit #
454. span style="color: goldenrod; font-weight: bold;"> 2022-07-12 11:16:17.456092 68.20% [DEBUG] switch_core_codec.c:248 sofia/default/4010463821@alpha.astppbilling.org:5060 Restore previous codec PCMA:8.
455. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 playback(/var/www/html/astpp//upload/pbx/taunt_.wav)
456. 2022-07-12 11:16:17.456092 68.20% [DEBUG] switch_core_file.c:444 File /var/www/html/astpp//upload/pbx/taunt_.wav sample rate 22257 doesn't match requested rate 8000
457. 2022-07-12 11:16:17.456092 68.20% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
458. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_ivr_play_say.c:2001 done playing file /var/www/html/astpp//upload/pbx/taunt_.wav
459. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 bridge_export(hold_music=local_stream://MusicOnHold)
460. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (bridge_export_vars) [hold_music]=[local_stream://MusicOnHold]
461. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(group_confirm_key=exec)
462. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [group_confirm_key]=[exec]
463. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(group_confirm_file=lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
464. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [group_confirm_file]=[lua /usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua]
465. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(is_recording=1)
466. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [is_recording]=[1]
467. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(media_bug_answer_req=true)
468. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [media_bug_answer_req]=[true]
469. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(RECORD_STEREO=true)
470. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RECORD_STEREO]=[true]
471. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(record_sample_rate=8000)
472. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [record_sample_rate]=[8000]
473. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(execute_on_answer=record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav)
474. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav]
475. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(ringback=)
476. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [ringback]=[UNDEF]
477. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(module_name=pbx)
478. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [module_name]=[pbx]
479. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 db(insert/pickup_any_group_/1/4d42da28-1d6b-4aa4-9226-07206ed77457)
480. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(dialed_extension=transfer_call)
481. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [dialed_extension]=[transfer_call]
482. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(dialed_extension=transfer_call)
483. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [dialed_extension]=[transfer_call]
484. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
485. 2022-07-12 11:16:21.556102 75.33% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
486. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
487. 2022-07-12 11:16:21.556102 75.33% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
488. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 export(ring_group_transfer=true)
489. 2022-07-12 11:16:21.556102 75.33% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [ring_group_transfer]=[true]
490. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(fail_on_single_reject=CALL_REJECTED)
491. 2022-07-12 11:16:21.556102 75.33% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [fail_on_single_reject]=[CALL_REJECTED]
492. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 bridge({sip_h_P-call_type='custom_forward'}[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=40,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=2357506173,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=40,leg_delay_start=0]user/2357506173@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=55,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=3937482392,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=55,leg_delay_start=0,absolute_codec_string=^^:PCMA:PCMU]user/3937482392@alpha.astppbilling.org,[sip_invite_params=user=local,sip_h_p-call_type='custom_forward',call_timeout=50,confirm=false,announcement=4010463821-20220712111610.wav,dialed_user=1190246984,is_intro_recorded=true,voicemail_sip=,voicemail_sip_domain=alpha.astppbilling.org,leg_timeout=50,leg_delay_start=0]user/1190246984@alpha.astppbilling.org,)
493. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
494. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
495. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
496. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
497. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
498. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
499. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
500. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
501. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
502. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
503. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
504. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
505. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
506. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
507. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
508. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
509. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
510. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
511. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
512. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
513. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
514. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
515. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
516. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
517. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
518. 2022-07-12 11:16:21.576077 75.33% [NOTICE] switch_channel.c:1123 New Channel sofia/default/2357506173@103.240.35.46:5062 [a5ad42fe-785a-4678-bdcd-fa149ad60f5f]
519. 2022-07-12 11:16:21.576077 75.33% [DEBUG] mod_sofia.c:5121 (sofia/default/2357506173@103.240.35.46:5062) State Change CS_NEW -> CS_INIT
520. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/2357506173@103.240.35.46:5062 Setting leg timeout to 40
521. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/2357506173@103.240.35.46:5062 Setting leg timeout to 40
522. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
523. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_INIT (Cur 2 Tot 381)
524. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2357506173@103.240.35.46:5062) State INIT
525. 2022-07-12 11:16:21.576077 75.33% [DEBUG] mod_sofia.c:97 sofia/default/2357506173@103.240.35.46:5062 SOFIA INIT
526. 2022-07-12 11:16:21.576077 75.33% [INFO] sofia_glue.c:1651 sofia/default/2357506173@103.240.35.46:5062 sending invite call-id: (null)
527. 2022-07-12 11:16:21.576077 75.33% [DEBUG] sofia_glue.c:1654 sofia/default/2357506173@103.240.35.46:5062 sending invite version: 1.10.7 -release 64bit
528. Local SDP:
529. v=0
530. o=FreeSWITCH 1657604303 1657604304 IN IP4 168.119.48.166
531. s=FreeSWITCH
532. c=IN IP4 168.119.48.166
533. t=0 0
534. m=audio 20278 RTP/AVP 8 101
535. a=rtpmap:8 PCMA/8000
536. a=rtpmap:101 telephone-event/8000
537. a=fmtp:101 0-15
538. a=ptime:20
539. a=sendrecv
540.  
541. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:40 sofia/default/2357506173@103.240.35.46:5062 Standard INIT
542. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:48 (sofia/default/2357506173@103.240.35.46:5062) State Change CS_INIT -> CS_ROUTING
543. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/2357506173@103.240.35.46:5062) State INIT going to sleep
544. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_ROUTING (Cur 2 Tot 381)
545. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2357506173@103.240.35.46:5062) State ROUTING
546. 2022-07-12 11:16:21.576077 75.33% [DEBUG] mod_sofia.c:158 sofia/default/2357506173@103.240.35.46:5062 SOFIA ROUTING
547. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:67 (sofia/default/2357506173@103.240.35.46:5062) State Change CS_ROUTING -> CS_CONSUME_MEDIA
548. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/2357506173@103.240.35.46:5062) State ROUTING going to sleep
549. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 381)
550. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2357506173@103.240.35.46:5062) State CONSUME_MEDIA
551. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/2357506173@103.240.35.46:5062) State CONSUME_MEDIA going to sleep
552. 2022-07-12 11:16:21.576077 75.33% [DEBUG] sofia.c:7499 Channel sofia/default/2357506173@103.240.35.46:5062 entering state [calling][0]
553. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
554. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
555. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
556. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
557. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
558. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
559. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
560. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
561. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
562. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
563. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
564. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
565. 2022-07-12 11:16:21.576077 75.33% [NOTICE] switch_channel.c:1123 New Channel sofia/default/3937482392@49.34.62.37:54845 [2f76902a-579d-4a4e-b67c-62fc2c9aa574]
566. 2022-07-12 11:16:21.576077 75.33% [DEBUG] mod_sofia.c:5121 (sofia/default/3937482392@49.34.62.37:54845) State Change CS_NEW -> CS_INIT
567. 2022-07-12 11:16:21.576077 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/3937482392@49.34.62.37:54845 Setting leg timeout to 55
568. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/3937482392@49.34.62.37:54845 Setting leg timeout to 55
569. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
570. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_INIT (Cur 3 Tot 382)
571. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/3937482392@49.34.62.37:54845) State INIT
572. 2022-07-12 11:16:21.596081 75.33% [DEBUG] mod_sofia.c:97 sofia/default/3937482392@49.34.62.37:54845 SOFIA INIT
573. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia_glue.c:1620 sip:3937482392@49.34.62.37:37373;rinstance=84f74dcaf99ff87d;transport=tcp Setting proxy route to sofia/default/3937482392@49.34.62.37:54845
574. 2022-07-12 11:16:21.596081 75.33% [INFO] sofia_glue.c:1651 sofia/default/3937482392@49.34.62.37:54845 sending invite call-id: (null)
575. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia_glue.c:1654 sofia/default/3937482392@49.34.62.37:54845 sending invite version: 1.10.7 -release 64bit
576. Local SDP:
577. v=0
578. o=FreeSWITCH 1657597043 1657597044 IN IP4 168.119.48.166
579. s=FreeSWITCH
580. c=IN IP4 168.119.48.166
581. t=0 0
582. m=audio 27538 RTP/AVP 8 0 101
583. a=rtpmap:8 PCMA/8000
584. a=rtpmap:0 PCMU/8000
585. a=rtpmap:101 telephone-event/8000
586. a=fmtp:101 0-15
587. a=ptime:20
588. a=sendrecv
589.  
590. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:40 sofia/default/3937482392@49.34.62.37:54845 Standard INIT
591. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:48 (sofia/default/3937482392@49.34.62.37:54845) State Change CS_INIT -> CS_ROUTING
592. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/3937482392@49.34.62.37:54845) State INIT going to sleep
593. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_ROUTING (Cur 3 Tot 382)
594. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/3937482392@49.34.62.37:54845) State ROUTING
595. 2022-07-12 11:16:21.596081 75.33% [DEBUG] mod_sofia.c:158 sofia/default/3937482392@49.34.62.37:54845 SOFIA ROUTING
596. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:67 (sofia/default/3937482392@49.34.62.37:54845) State Change CS_ROUTING -> CS_CONSUME_MEDIA
597. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/3937482392@49.34.62.37:54845) State ROUTING going to sleep
598. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_CONSUME_MEDIA (Cur 3 Tot 382)
599. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia.c:7499 Channel sofia/default/3937482392@49.34.62.37:54845 entering state [calling][0]
600. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/3937482392@49.34.62.37:54845) State CONSUME_MEDIA
601. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/3937482392@49.34.62.37:54845) State CONSUME_MEDIA going to sleep
602. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
603. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
604. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
605. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
606. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
607. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
608. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
609. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
610. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
611. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
612. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
613. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
614. 2022-07-12 11:16:21.596081 75.33% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1190246984@103.240.35.46:60415 [ddc008cc-5600-44ee-96c2-a57b8de64c16]
615. 2022-07-12 11:16:21.596081 75.33% [DEBUG] mod_sofia.c:5121 (sofia/default/1190246984@103.240.35.46:60415) State Change CS_NEW -> CS_INIT
616. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/1190246984@103.240.35.46:60415 Setting leg timeout to 50
617. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:3151 sofia/default/1190246984@103.240.35.46:60415 Setting leg timeout to 50
618. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_INIT (Cur 4 Tot 383)
619. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1190246984@103.240.35.46:60415) State INIT
620. 2022-07-12 11:16:21.596081 75.33% [DEBUG] mod_sofia.c:97 sofia/default/1190246984@103.240.35.46:60415 SOFIA INIT
621. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia_glue.c:1620 sip:1190246984@103.240.35.46:33641;transport=TCP;rinstance=b9792ae31a1a1a6a Setting proxy route to sofia/default/1190246984@103.240.35.46:60415
622. 2022-07-12 11:16:21.596081 75.33% [INFO] sofia_glue.c:1651 sofia/default/1190246984@103.240.35.46:60415 sending invite call-id: (null)
623. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia_glue.c:1654 sofia/default/1190246984@103.240.35.46:60415 sending invite version: 1.10.7 -release 64bit
624. Local SDP:
625. v=0
626. o=FreeSWITCH 1657602973 1657602974 IN IP4 168.119.48.166
627. s=FreeSWITCH
628. c=IN IP4 168.119.48.166
629. t=0 0
630. m=audio 21608 RTP/AVP 8 101
631. a=rtpmap:8 PCMA/8000
632. a=rtpmap:101 telephone-event/8000
633. a=fmtp:101 0-15
634. a=ptime:20
635. a=sendrecv
636.  
637. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:40 sofia/default/1190246984@103.240.35.46:60415 Standard INIT
638. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1190246984@103.240.35.46:60415) State Change CS_INIT -> CS_ROUTING
639. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1190246984@103.240.35.46:60415) State INIT going to sleep
640. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_ROUTING (Cur 4 Tot 383)
641. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1190246984@103.240.35.46:60415) State ROUTING
642. 2022-07-12 11:16:21.596081 75.33% [DEBUG] mod_sofia.c:158 sofia/default/1190246984@103.240.35.46:60415 SOFIA ROUTING
643. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1190246984@103.240.35.46:60415) State Change CS_ROUTING -> CS_CONSUME_MEDIA
644. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1190246984@103.240.35.46:60415) State ROUTING going to sleep
645. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_CONSUME_MEDIA (Cur 4 Tot 383)
646. 2022-07-12 11:16:21.596081 75.33% [DEBUG] sofia.c:7499 Channel sofia/default/1190246984@103.240.35.46:60415 entering state [calling][0]
647. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1190246984@103.240.35.46:60415) State CONSUME_MEDIA
648. 2022-07-12 11:16:21.596081 75.33% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1190246984@103.240.35.46:60415) State CONSUME_MEDIA going to sleep
649. 2022-07-12 11:16:21.756089 75.33% [DEBUG] sofia.c:7499 Channel sofia/default/2357506173@103.240.35.46:5062 entering state [proceeding][180]
650. 2022-07-12 11:16:21.756089 75.33% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/2357506173@103.240.35.46:5062!
651. 2022-07-12 11:16:21.756089 75.33% [DEBUG] switch_channel.c:3494 (sofia/default/2357506173@103.240.35.46:5062) Callstate Change DOWN -> RINGING
652. 2022-07-12 11:16:23.036084 77.73% [DEBUG] sofia.c:7499 Channel sofia/default/3937482392@49.34.62.37:54845 entering state [proceeding][180]
653. 2022-07-12 11:16:23.036084 77.73% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/3937482392@49.34.62.37:54845!
654. 2022-07-12 11:16:23.036084 77.73% [DEBUG] switch_channel.c:3494 (sofia/default/3937482392@49.34.62.37:54845) Callstate Change DOWN -> RINGING
655. 2022-07-12 11:16:25.036085 79.73% [DEBUG] sofia.c:7499 Channel sofia/default/2357506173@103.240.35.46:5062 entering state [completing][200]
656. 2022-07-12 11:16:25.036085 79.73% [DEBUG] sofia.c:7509 Remote SDP:
657. v=0
658. o=2357506173 8001 8000 IN IP4 103.240.35.46
659. s=SIP Call
660. c=IN IP4 103.240.35.46
661. t=0 0
662. m=audio 5008 RTP/AVP 8 101
663. a=rtpmap:8 PCMA/8000
664. a=rtpmap:101 telephone-event/8000
665. a=fmtp:101 0-15
666. a=ptime:20
667.  
668. 2022-07-12 11:16:25.036085 79.73% [DEBUG] sofia.c:7499 Channel sofia/default/2357506173@103.240.35.46:5062 entering state [ready][200]
669. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
670. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
671. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
672. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/2357506173@103.240.35.46:5062 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
673. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_codec.c:111 sofia/default/2357506173@103.240.35.46:5062 Original read codec set to PCMA:8
674. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
675. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:5973 sofia/default/2357506173@103.240.35.46:5062 Set 2833 dtmf send payload to 101 recv payload to 101
676. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/2357506173@103.240.35.46:5062] 168.119.48.166 port 20278 -> 103.240.35.46 port 5008 codec: 8 ms: 20
677. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
678. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:9089 sofia/default/2357506173@103.240.35.46:5062 Set 2833 dtmf send payload to 101
679. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:9096 sofia/default/2357506173@103.240.35.46:5062 Set 2833 dtmf receive payload to 101
680. 2022-07-12 11:16:25.036085 79.73% [DEBUG] switch_core_media.c:9119 sofia/default/2357506173@103.240.35.46:5062 Set rtp dtmf delay to 40
681. 2022-07-12 11:16:25.036085 79.73% [NOTICE] sofia.c:8683 Channel [sofia/default/2357506173@103.240.35.46:5062] has been answered
682. EXECUTE [depth=1] sofia/default/2357506173@103.240.35.46:5062 record_session(/usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav)
683. EXECUTE [depth=1] sofia/default/2357506173@103.240.35.46:5062 lua(/usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
684. 2022-07-12 11:16:25.056082 79.73% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
685. 2022-07-12 11:16:25.056082 79.73% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/2357506173@103.240.35.46:5062
686. 2022-07-12 11:16:25.056082 79.73% [DEBUG] switch_channel.c:3950 (sofia/default/2357506173@103.240.35.46:5062) Callstate Change RINGING -> ACTIVE
687. 2022-07-12 11:16:25.056082 79.73% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group]  Ring group confirm flag :false
688. 2022-07-12 11:16:25.056082 79.73% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group] [Confirm.lua] : Ring group : is_intro_recorded - true
689. EXECUTE [depth=0] sofia/default/2357506173@103.240.35.46:5062 playback(/opt/ASTPP/web_interface/astpp/upload/callscreening/4010463821-20220712111610.wav)
690. 2022-07-12 11:16:25.056082 79.73% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
691. 2022-07-12 11:16:25.056082 79.73% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
692. 2022-07-12 11:16:25.356086 79.33% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
693. 2022-07-12 11:16:25.356086 79.33% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
694. 2022-07-12 11:16:27.596107 76.43% [DEBUG] switch_ivr_play_say.c:2001 done playing file /opt/ASTPP/web_interface/astpp/upload/callscreening/4010463821-20220712111610.wav
695. 2022-07-12 11:16:27.596107 76.43% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group] Ring group confirm :: true
696. 2022-07-12 11:16:27.596107 76.43% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group] Ring group prompt :: astpp-prompt.wav ::
697. 2022-07-12 11:16:27.596107 76.43% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-prompt.wav sample rate 22050 doesn't match requested rate 8000
698. 2022-07-12 11:16:27.596107 76.43% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
699. 2022-07-12 11:16:28.196086 76.43% [DEBUG] sofia.c:7499 Channel sofia/default/1190246984@103.240.35.46:60415 entering state [proceeding][180]
700. 2022-07-12 11:16:28.196086 76.43% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/1190246984@103.240.35.46:60415!
701. 2022-07-12 11:16:28.196086 76.43% [DEBUG] switch_channel.c:3494 (sofia/default/1190246984@103.240.35.46:60415) Callstate Change DOWN -> RINGING
702. 2022-07-12 11:16:33.476082 71.67% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-prompt.wav
703. 2022-07-12 11:16:34.076094 71.67% [DEBUG] switch_core_file.c:444 File /usr/local/freeswitch/sounds/en/us/callie/astpp-prompt.wav sample rate 22050 doesn't match requested rate 8000
704. 2022-07-12 11:16:34.076094 71.67% [DEBUG] switch_ivr_play_say.c:1556 Codec Activated L16@8000hz 1 channels 20ms
705. 2022-07-12 11:16:34.796082 70.67% [DEBUG] switch_rtp.c:8179 RTP RECV DTMF 3:1280
706. 2022-07-12 11:16:34.796082 70.67% [INFO] switch_channel.c:527 RECV DTMF 3:1280
707. 2022-07-12 11:16:34.796082 70.67% [DEBUG] switch_ivr_play_say.c:2001 done playing file /usr/local/freeswitch/sounds/en/us/callie/astpp-prompt.wav
708. 2022-07-12 11:16:34.936110 70.67% [INFO] switch_channel.c:527 RECV DTMF 3:1920
709. 2022-07-12 11:16:34.936110 70.67% [DEBUG] sofia.c:10126 INFO DTMF(3)
710. 2022-07-12 11:16:34.936110 70.67% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group]  Ring group confirm get digit :33
711. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup
712. 2022-07-12 11:16:34.936110 70.67% [NOTICE] switch_cpp.cpp:751 Hangup sofia/default/2357506173@103.240.35.46:5062 [CS_CONSUME_MEDIA] [NO_ANSWER]
713. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_HANGUP (Cur 4 Tot 383)
714. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav
715. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_cpp.cpp:1209 sofia/default/2357506173@103.240.35.46:5062 destroy/unlink session from object
716. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_session.c:2983 sofia/default/2357506173@103.240.35.46:5062 skip receive message [PHONE_EVENT] (channel is hungup already)
717. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
718. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/2357506173@103.240.35.46:5062
719. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:844 (sofia/default/2357506173@103.240.35.46:5062) Callstate Change ACTIVE -> HANGUP
720. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2357506173@103.240.35.46:5062) State HANGUP
721. 2022-07-12 11:16:34.936110 70.67% [DEBUG] mod_sofia.c:468 Channel sofia/default/2357506173@103.240.35.46:5062 hanging up, cause: NO_ANSWER
722. 2022-07-12 11:16:34.936110 70.67% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/2357506173@103.240.35.46:5062
723. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:59 sofia/default/2357506173@103.240.35.46:5062 Standard HANGUP, cause: NO_ANSWER
724. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:846 (sofia/default/2357506173@103.240.35.46:5062) State HANGUP going to sleep
725. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:616 (sofia/default/2357506173@103.240.35.46:5062) State Change CS_HANGUP -> CS_REPORTING
726. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_REPORTING (Cur 4 Tot 383)
727. 2022-07-12 11:16:34.936110 70.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2357506173@103.240.35.46:5062) State REPORTING
728. 2022-07-12 11:16:34.936110 70.67% [INFO] mod_json_cdr.c:271 Process [a5ad42fe-785a-4678-bdcd-fa149ad60f5f.cdr.json]
729. 2022-07-12 11:16:35.016093 70.67% [DEBUG] switch_core_state_machine.c:168 sofia/default/2357506173@103.240.35.46:5062 Standard REPORTING, cause: NO_ANSWER
730. 2022-07-12 11:16:35.016093 70.67% [DEBUG] switch_core_state_machine.c:932 (sofia/default/2357506173@103.240.35.46:5062) State REPORTING going to sleep
731. 2022-07-12 11:16:35.016093 70.67% [DEBUG] switch_core_state_machine.c:607 (sofia/default/2357506173@103.240.35.46:5062) State Change CS_REPORTING -> CS_DESTROY
732. 2022-07-12 11:16:35.036081 70.67% [DEBUG] switch_core_session.c:1753 Session 381 (sofia/default/2357506173@103.240.35.46:5062) Locked, Waiting on external entities
733. 2022-07-12 11:17:12.016091 90.17% [NOTICE] switch_ivr_originate.c:364 Hangup sofia/default/1190246984@103.240.35.46:60415 [CS_CONSUME_MEDIA] [ALLOTTED_TIMEOUT]
734. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_HANGUP (Cur 4 Tot 383)
735. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1190246984@103.240.35.46:60415) Callstate Change RINGING -> HANGUP
736. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1190246984@103.240.35.46:60415) State HANGUP
737. 2022-07-12 11:17:12.016091 90.17% [DEBUG] mod_sofia.c:468 Channel sofia/default/1190246984@103.240.35.46:60415 hanging up, cause: ALLOTTED_TIMEOUT
738. 2022-07-12 11:17:12.016091 90.17% [DEBUG] mod_sofia.c:536 Sending CANCEL to sofia/default/1190246984@103.240.35.46:60415
739. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:59 sofia/default/1190246984@103.240.35.46:60415 Standard HANGUP, cause: ALLOTTED_TIMEOUT
740. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1190246984@103.240.35.46:60415) State HANGUP going to sleep
741. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1190246984@103.240.35.46:60415) State Change CS_HANGUP -> CS_REPORTING
742. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_REPORTING (Cur 4 Tot 383)
743. 2022-07-12 11:17:12.016091 90.17% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1190246984@103.240.35.46:60415) State REPORTING
744. 2022-07-12 11:17:12.016091 90.17% [INFO] mod_json_cdr.c:271 Process [ddc008cc-5600-44ee-96c2-a57b8de64c16.cdr.json]
745. 2022-07-12 11:17:12.036096 90.17% [DEBUG] switch_core_state_machine.c:168 sofia/default/1190246984@103.240.35.46:60415 Standard REPORTING, cause: ALLOTTED_TIMEOUT
746. 2022-07-12 11:17:12.036096 90.17% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1190246984@103.240.35.46:60415) State REPORTING going to sleep
747. 2022-07-12 11:17:12.036096 90.17% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1190246984@103.240.35.46:60415) State Change CS_REPORTING -> CS_DESTROY
748. 2022-07-12 11:17:12.036096 90.17% [DEBUG] switch_core_session.c:1753 Session 383 (sofia/default/1190246984@103.240.35.46:60415) Locked, Waiting on external entities
749. 2022-07-12 11:17:17.016104 89.57% [NOTICE] switch_ivr_originate.c:364 Hangup sofia/default/3937482392@49.34.62.37:54845 [CS_CONSUME_MEDIA] [ALLOTTED_TIMEOUT]
750. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_HANGUP (Cur 4 Tot 383)
751. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:844 (sofia/default/3937482392@49.34.62.37:54845) Callstate Change RINGING -> HANGUP
752. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:846 (sofia/default/3937482392@49.34.62.37:54845) State HANGUP
753. 2022-07-12 11:17:17.016104 89.57% [DEBUG] mod_sofia.c:468 Channel sofia/default/3937482392@49.34.62.37:54845 hanging up, cause: ALLOTTED_TIMEOUT
754. 2022-07-12 11:17:17.016104 89.57% [DEBUG] mod_sofia.c:536 Sending CANCEL to sofia/default/3937482392@49.34.62.37:54845
755. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:59 sofia/default/3937482392@49.34.62.37:54845 Standard HANGUP, cause: ALLOTTED_TIMEOUT
756. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:846 (sofia/default/3937482392@49.34.62.37:54845) State HANGUP going to sleep
757. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:616 (sofia/default/3937482392@49.34.62.37:54845) State Change CS_HANGUP -> CS_REPORTING
758. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_REPORTING (Cur 4 Tot 383)
759. 2022-07-12 11:17:17.016104 89.57% [DEBUG] switch_core_state_machine.c:932 (sofia/default/3937482392@49.34.62.37:54845) State REPORTING
760. 2022-07-12 11:17:17.016104 89.57% [INFO] mod_json_cdr.c:271 Process [2f76902a-579d-4a4e-b67c-62fc2c9aa574.cdr.json]
761. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:168 sofia/default/3937482392@49.34.62.37:54845 Standard REPORTING, cause: ALLOTTED_TIMEOUT
762. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:932 (sofia/default/3937482392@49.34.62.37:54845) State REPORTING going to sleep
763. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:607 (sofia/default/3937482392@49.34.62.37:54845) State Change CS_REPORTING -> CS_DESTROY
764. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_session.c:1753 Session 382 (sofia/default/3937482392@49.34.62.37:54845) Locked, Waiting on external entities
765. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_ivr_originate.c:4035 Originate Resulted in Error Cause: 19 [NO_ANSWER]
766. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1771 Session 381 (sofia/default/2357506173@103.240.35.46:5062) Ended
767. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1771 Session 382 (sofia/default/3937482392@49.34.62.37:54845) Ended
768. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/2357506173@103.240.35.46:5062 [CS_DESTROY]
769. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/3937482392@49.34.62.37:54845 [CS_DESTROY]
770. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1771 Session 383 (sofia/default/1190246984@103.240.35.46:60415) Ended
771. 2022-07-12 11:17:17.036078 89.57% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1190246984@103.240.35.46:60415 [CS_DESTROY]
772. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:735 (sofia/default/2357506173@103.240.35.46:5062) Running State Change CS_DESTROY (Cur 1 Tot 383)
773. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:735 (sofia/default/3937482392@49.34.62.37:54845) Running State Change CS_DESTROY (Cur 1 Tot 383)
774. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/3937482392@49.34.62.37:54845) State DESTROY
775. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_sofia.c:379 sofia/default/3937482392@49.34.62.37:54845 SOFIA DESTROY
776. 2022-07-12 11:17:17.036078 89.57% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: NO_ANSWER
777. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:175 sofia/default/3937482392@49.34.62.37:54845 Standard DESTROY
778. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/3937482392@49.34.62.37:54845) State DESTROY going to sleep
779. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1190246984@103.240.35.46:60415) Running State Change CS_DESTROY (Cur 1 Tot 383)
780. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1190246984@103.240.35.46:60415) State DESTROY
781. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_sofia.c:379 sofia/default/1190246984@103.240.35.46:60415 SOFIA DESTROY
782. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:175 sofia/default/1190246984@103.240.35.46:60415 Standard DESTROY
783. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1190246984@103.240.35.46:60415) State DESTROY going to sleep
784. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2357506173@103.240.35.46:5062) State DESTROY
785. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_sofia.c:379 sofia/default/2357506173@103.240.35.46:5062 SOFIA DESTROY
786. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:175 sofia/default/2357506173@103.240.35.46:5062 Standard DESTROY
787. 2022-07-12 11:17:17.036078 89.57% [DEBUG] switch_core_state_machine.c:745 (sofia/default/2357506173@103.240.35.46:5062) State DESTROY going to sleep
788. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(original_did_number=107770)
789. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [original_did_number]=[107770]
790. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(no_answer_number=)
791. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [no_answer_number]=[UNDEF]
792. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(no_answer_call_type=extension)
793. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [no_answer_call_type]=[extension]
794. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(no_answer_call_type_value=53)
795. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [no_answer_call_type_value]=[53]
796. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(variable_sip_to_host=alpha.astppbilling.org)
797. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [variable_sip_to_host]=[alpha.astppbilling.org]
798. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(userinfo_id=49)
799. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [userinfo_id]=[49]
800. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(variable_sip_to_host=alpha.astppbilling.org)
801. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [variable_sip_to_host]=[alpha.astppbilling.org]
802. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 set(opensips_flag=1)
803. 2022-07-12 11:17:17.036078 89.57% [DEBUG] mod_dptools.c:1685 SET sofia/default/4010463821@alpha.astppbilling.org:5060 [opensips_flag]=[1]
804. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 lua(astpp/lib/pbx/astpp.ring_group_destination.lua)
805. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
806. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')
807. 2022-07-12 11:17:17.056131 89.57% [WARNING] switch_cpp.cpp:1465 [ASTPP] This Is Last Dispositions NO_ANSWER This Is No Answer Number : 53
808. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET multidomain] Query :SELECT username FROM sip_devices WHERE id = 53 limit 1
809. 2022-07-12 11:17:17.056131 89.57% [INFO] switch_cpp.cpp:1465 [ASTPP] [SIP_CODEC13]
810. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [custom_domain] user_domain :alpha.astppbilling.org
811. 2022-07-12 11:17:17.056131 89.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 ,domains WHERE accounts.id=domains.accountid AND accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="1246167409"  AND domains.domain="alpha.astppbilling.org" limit 1
812. 2022-07-12 11:17:17.056131 89.57% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] sip_codec : PCMA,PCMU
813. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 bridge({ignore_early_media=true}[absolute_codec_string=^^:PCMA:PCMU]user/1246167409@alpha.astppbilling.org)
814. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
815. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
816. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
817. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
818. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
819. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
820. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
821. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
822. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
823. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
824. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
825. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
826. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_ivr_originate.c:2843 Parsing session specific variables
827. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [domain_name]=[alpha.astppbilling.org] to event
828. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [sip_to_host]=[alpha.astppbilling.org] to event
829. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [presence_data]=[x|||PK-Reseller(4010463821)|||||||||DID|||103.240.35.46] to event
830. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [call_type]=[7] to event
831. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [is_recording]=[1] to event
832. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
833. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [RECORD_STEREO]=[true] to event
834. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
835. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav] to event
836. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [dialed_extension]=[transfer_call] to event
837. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_channel.c:1269 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[export_vars] [ring_group_transfer]=[true] to event
838. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_ivr_originate.c:2281 Parsing global variables
839. 2022-07-12 11:17:17.056131 89.57% [NOTICE] switch_channel.c:1123 New Channel sofia/default/1246167409@103.240.35.46:60464 [98a4409d-27f7-49eb-8d32-46969f32f06f]
840. 2022-07-12 11:17:17.056131 89.57% [DEBUG] mod_sofia.c:5121 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_NEW -> CS_INIT
841. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_INIT (Cur 2 Tot 384)
842. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1246167409@103.240.35.46:60464) State INIT
843. 2022-07-12 11:17:17.056131 89.57% [DEBUG] mod_sofia.c:97 sofia/default/1246167409@103.240.35.46:60464 SOFIA INIT
844. 2022-07-12 11:17:17.056131 89.57% [DEBUG] sofia_glue.c:1620 sip:1246167409@103.240.35.46:38230;rinstance=6974084640587818;transport=TCP Setting proxy route to sofia/default/1246167409@103.240.35.46:60464
845. 2022-07-12 11:17:17.056131 89.57% [INFO] sofia_glue.c:1651 sofia/default/1246167409@103.240.35.46:60464 sending invite call-id: (null)
846. 2022-07-12 11:17:17.056131 89.57% [DEBUG] sofia_glue.c:1654 sofia/default/1246167409@103.240.35.46:60464 sending invite version: 1.10.7 -release 64bit
847. Local SDP:
848. v=0
849. o=FreeSWITCH 1657597269 1657597270 IN IP4 168.119.48.166
850. s=FreeSWITCH
851. c=IN IP4 168.119.48.166
852. t=0 0
853. m=audio 27368 RTP/AVP 8 0 101
854. a=rtpmap:8 PCMA/8000
855. a=rtpmap:0 PCMU/8000
856. a=rtpmap:101 telephone-event/8000
857. a=fmtp:101 0-15
858. a=ptime:20
859. a=sendrecv
860.  
861. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:40 sofia/default/1246167409@103.240.35.46:60464 Standard INIT
862. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:48 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_INIT -> CS_ROUTING
863. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:624 (sofia/default/1246167409@103.240.35.46:60464) State INIT going to sleep
864. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_ROUTING (Cur 2 Tot 384)
865. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1246167409@103.240.35.46:60464) State ROUTING
866. 2022-07-12 11:17:17.056131 89.57% [DEBUG] mod_sofia.c:158 sofia/default/1246167409@103.240.35.46:60464 SOFIA ROUTING
867. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_ivr_originate.c:67 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_ROUTING -> CS_CONSUME_MEDIA
868. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:640 (sofia/default/1246167409@103.240.35.46:60464) State ROUTING going to sleep
869. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 384)
870. 2022-07-12 11:17:17.056131 89.57% [DEBUG] sofia.c:7499 Channel sofia/default/1246167409@103.240.35.46:60464 entering state [calling][0]
871. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1246167409@103.240.35.46:60464) State CONSUME_MEDIA
872. 2022-07-12 11:17:17.056131 89.57% [DEBUG] switch_core_state_machine.c:659 (sofia/default/1246167409@103.240.35.46:60464) State CONSUME_MEDIA going to sleep
873. 2022-07-12 11:17:17.736092 89.13% [DEBUG] sofia.c:7499 Channel sofia/default/1246167409@103.240.35.46:60464 entering state [proceeding][180]
874. 2022-07-12 11:17:17.736092 89.13% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/1246167409@103.240.35.46:60464!
875. 2022-07-12 11:17:17.736092 89.13% [DEBUG] switch_channel.c:3494 (sofia/default/1246167409@103.240.35.46:60464) Callstate Change DOWN -> RINGING
876. 2022-07-12 11:17:28.196093 90.27% [DEBUG] sofia.c:7499 Channel sofia/default/1246167409@103.240.35.46:60464 entering state [completing][200]
877. 2022-07-12 11:17:28.196093 90.27% [DEBUG] sofia.c:7509 Remote SDP:
878. v=0
879. o=Z 0 3 IN IP4 103.240.35.46
880. s=Z
881. c=IN IP4 103.240.35.46
882. t=0 0
883. m=audio 8002 RTP/AVP 8 3 110 0 98 101
884. a=rtpmap:110 speex/8000
885. a=rtpmap:98 iLBC/8000
886. a=fmtp:98 mode=20
887. a=rtpmap:101 telephone-event/8000
888. a=fmtp:101 0-15
889.  
890. 2022-07-12 11:17:28.196093 90.27% [DEBUG] sofia.c:7499 Channel sofia/default/1246167409@103.240.35.46:60464 entering state [ready][200]
891. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
892. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
893. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
894. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
895. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
896. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
897. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
898. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
899. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
900. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
901. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
902. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
903. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
904. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/1246167409@103.240.35.46:60464 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
905. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_codec.c:111 sofia/default/1246167409@103.240.35.46:60464 Original read codec set to PCMA:8
906. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
907. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:5973 sofia/default/1246167409@103.240.35.46:60464 Set 2833 dtmf send payload to 101 recv payload to 101
908. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/default/1246167409@103.240.35.46:60464] 168.119.48.166 port 27368 -> 103.240.35.46 port 8002 codec: 8 ms: 20
909. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
910. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:9089 sofia/default/1246167409@103.240.35.46:60464 Set 2833 dtmf send payload to 101
911. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:9096 sofia/default/1246167409@103.240.35.46:60464 Set 2833 dtmf receive payload to 101
912. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media.c:9119 sofia/default/1246167409@103.240.35.46:60464 Set rtp dtmf delay to 40
913. 2022-07-12 11:17:28.196093 90.27% [NOTICE] sofia.c:8683 Channel [sofia/default/1246167409@103.240.35.46:60464] has been answered
914. EXECUTE [depth=1] sofia/default/1246167409@103.240.35.46:60464 record_session(/usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav)
915. EXECUTE [depth=1] sofia/default/1246167409@103.240.35.46:60464 lua(/usr/local/freeswitch/scripts/astpp/lib/pbx/astpp.confirm.lua)
916. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
917. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/1246167409@103.240.35.46:60464
918. 2022-07-12 11:17:28.196093 90.27% [DEBUG] switch_channel.c:3950 (sofia/default/1246167409@103.240.35.46:60464) Callstate Change RINGING -> ACTIVE
919. 2022-07-12 11:17:28.216086 90.27% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group]  Ring group confirm flag :false
920. 2022-07-12 11:17:28.216086 90.27% [NOTICE] switch_cpp.cpp:1465 [Astpp_Call-Ring Group] Ring group confirm :: false
921. 2022-07-12 11:17:28.216086 90.27% [DEBUG] switch_cpp.cpp:1209 sofia/default/1246167409@103.240.35.46:60464 destroy/unlink session from object
922. 2022-07-12 11:17:28.216086 90.27% [DEBUG] switch_core_media.c:8759 Audio params are unchanged for sofia/default/4010463821@alpha.astppbilling.org:5060.
923. 2022-07-12 11:17:28.216086 90.27% [DEBUG] mod_sofia.c:913 Local SDP sofia/default/4010463821@alpha.astppbilling.org:5060:
924. v=0
925. o=FreeSWITCH 1657605342 1657605344 IN IP4 168.119.48.166
926. s=FreeSWITCH
927. c=IN IP4 168.119.48.166
928. t=0 0
929. m=audio 19228 RTP/AVP 8 101
930. a=rtpmap:8 PCMA/8000
931. a=rtpmap:101 telephone-event/8000
932. a=fmtp:101 0-15
933. a=ptime:20
934. a=sendrecv
935.  
936. 2022-07-12 11:17:28.216086 90.27% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/4010463821@alpha.astppbilling.org:5060] has been answered
937. 2022-07-12 11:17:28.216086 90.27% [DEBUG] sofia.c:7499 Channel sofia/default/4010463821@alpha.astppbilling.org:5060 entering state [completed][200]
938. EXECUTE [depth=0] sofia/default/4010463821@alpha.astppbilling.org:5060 record_session(/usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav)
939. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_async.c:1503 Record session sample rate: 8000 -> 8000
940. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_core_media_bug.c:978 Attaching BUG to sofia/default/4010463821@alpha.astppbilling.org:5060
941. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_channel.c:3950 (sofia/default/4010463821@alpha.astppbilling.org:5060) Callstate Change EARLY -> ACTIVE
942. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/1246167409@103.240.35.46:60464] Peer UUID: 98a4409d-27f7-49eb-8d32-46969f32f06f
943. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_originate.c:3892 Originate Resulted in Success: [sofia/default/1246167409@103.240.35.46:60464] Peer UUID: 98a4409d-27f7-49eb-8d32-46969f32f06f
944. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_channel.c:1276 sofia/default/4010463821@alpha.astppbilling.org:5060 EXPORTING[bridge_export_vars] [hold_music]=[local_stream://MusicOnHold] to sofia/default/1246167409@103.240.35.46:60464
945. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
946. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 384)
947. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_core_state_machine.c:650 (sofia/default/1246167409@103.240.35.46:60464) State EXCHANGE_MEDIA
948. 2022-07-12 11:17:28.236082 90.27% [DEBUG] mod_sofia.c:671 SOFIA EXCHANGE_MEDIA
949. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
950. 2022-07-12 11:17:28.236082 90.27% [DEBUG] switch_ivr_async.c:1777 No silence detection configured; assuming start of speech
951. 2022-07-12 11:17:28.256154 90.27% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
952. 2022-07-12 11:17:28.256154 90.27% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
953. 2022-07-12 11:17:28.256154 90.27% [DEBUG] switch_rtp.c:7934 Correct audio ip/port confirmed.
954. 2022-07-12 11:17:28.256154 90.27% [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
955. 2022-07-12 11:17:28.416089 90.03% [DEBUG] switch_ivr_bridge.c:655 sofia/default/1246167409@103.240.35.46:60464 Bridge execute app sched_hangup(+6000 normal_clearing)
956. 2022-07-12 11:17:28.416089 90.03% [DEBUG] sofia.c:7499 Channel sofia/default/4010463821@alpha.astppbilling.org:5060 entering state [ready][200]
957. 2022-07-12 11:17:28.436085 90.03% [DEBUG] switch_ivr.c:632 sofia/default/1246167409@103.240.35.46:60464 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
958. EXECUTE [depth=0] sofia/default/1246167409@103.240.35.46:60464 sched_hangup(+6000 normal_clearing)
959. 2022-07-12 11:17:28.436085 90.03% [DEBUG] switch_scheduler.c:263 Added task 48 switch_ivr_schedule_hangup (98a4409d-27f7-49eb-8d32-46969f32f06f) to run at 1657630648
960. 2022-07-12 11:17:35.116095 78.03% [NOTICE] sofia.c:1065 Hangup sofia/default/1246167409@103.240.35.46:60464 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
961. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/1246167409@103.240.35.46:60464]
962. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:650 (sofia/default/1246167409@103.240.35.46:60464) State EXCHANGE_MEDIA going to sleep
963. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_HANGUP (Cur 2 Tot 384)
964. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav
965. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
966. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/1246167409@103.240.35.46:60464
967. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:844 (sofia/default/1246167409@103.240.35.46:60464) Callstate Change ACTIVE -> HANGUP
968. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1246167409@103.240.35.46:60464) State HANGUP
969. 2022-07-12 11:17:35.116095 78.03% [DEBUG] mod_sofia.c:468 Channel sofia/default/1246167409@103.240.35.46:60464 hanging up, cause: NORMAL_CLEARING
970. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:59 sofia/default/1246167409@103.240.35.46:60464 Standard HANGUP, cause: NORMAL_CLEARING
971. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/1246167409@103.240.35.46:60464) State HANGUP going to sleep
972. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:616 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_HANGUP -> CS_REPORTING
973. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_REPORTING (Cur 2 Tot 384)
974. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1246167409@103.240.35.46:60464) State REPORTING
975. 2022-07-12 11:17:35.116095 78.03% [INFO] mod_json_cdr.c:271 Process [98a4409d-27f7-49eb-8d32-46969f32f06f.cdr.json]
976. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_bridge.c:820 sofia/default/1246167409@103.240.35.46:60464 ending bridge by request from write function
977. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/4010463821@alpha.astppbilling.org:5060]
978. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_bridge.c:1892 sofia/default/1246167409@103.240.35.46:60464 skip receive message [UNBRIDGE] (channel is hungup already)
979. 2022-07-12 11:17:35.116095 78.03% [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/default/4010463821@alpha.astppbilling.org:5060 [CS_EXECUTE] [NORMAL_CLEARING]
980. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_session.c:2983 sofia/default/4010463821@alpha.astppbilling.org:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
981. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_cpp.cpp:1209 sofia/default/4010463821@alpha.astppbilling.org:5060 destroy/unlink session from object
982. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_session.c:2983 sofia/default/4010463821@alpha.astppbilling.org:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
983. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:647 (sofia/default/4010463821@alpha.astppbilling.org:5060) State EXECUTE going to sleep
984. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_HANGUP (Cur 2 Tot 384)
985. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_async.c:1596 Stop recording file /usr/local/freeswitch/recordings/4d42da28-1d6b-4aa4-9226-07206ed77457.wav
986. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_ivr_async.c:1677 Channel is hung up
987. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_media_bug.c:1328 Removing BUG from sofia/default/4010463821@alpha.astppbilling.org:5060
988. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:844 (sofia/default/4010463821@alpha.astppbilling.org:5060) Callstate Change ACTIVE -> HANGUP
989. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4010463821@alpha.astppbilling.org:5060) State HANGUP
990. 2022-07-12 11:17:35.116095 78.03% [DEBUG] mod_sofia.c:462 sofia/default/4010463821@alpha.astppbilling.org:5060 Overriding SIP cause 480 with 200 from the other leg
991. 2022-07-12 11:17:35.116095 78.03% [DEBUG] mod_sofia.c:468 Channel sofia/default/4010463821@alpha.astppbilling.org:5060 hanging up, cause: NORMAL_CLEARING
992. 2022-07-12 11:17:35.116095 78.03% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/default/4010463821@alpha.astppbilling.org:5060
993. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:59 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard HANGUP, cause: NORMAL_CLEARING
994. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:846 (sofia/default/4010463821@alpha.astppbilling.org:5060) State HANGUP going to sleep
995. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:616 (sofia/default/4010463821@alpha.astppbilling.org:5060) State Change CS_HANGUP -> CS_REPORTING
996. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:581 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_REPORTING (Cur 2 Tot 384)
997. 2022-07-12 11:17:35.116095 78.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4010463821@alpha.astppbilling.org:5060) State REPORTING
998. 2022-07-12 11:17:35.116095 78.03% [INFO] mod_json_cdr.c:271 Process [4d42da28-1d6b-4aa4-9226-07206ed77457.cdr.json]
999. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:168 sofia/default/1246167409@103.240.35.46:60464 Standard REPORTING, cause: NORMAL_CLEARING
1000. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/1246167409@103.240.35.46:60464) State REPORTING going to sleep
1001. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:607 (sofia/default/1246167409@103.240.35.46:60464) State Change CS_REPORTING -> CS_DESTROY
1002. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_session.c:1753 Session 384 (sofia/default/1246167409@103.240.35.46:60464) Locked, Waiting on external entities
1003. 2022-07-12 11:17:35.136078 78.03% [NOTICE] switch_core_session.c:1771 Session 384 (sofia/default/1246167409@103.240.35.46:60464) Ended
1004. 2022-07-12 11:17:35.136078 78.03% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/1246167409@103.240.35.46:60464 [CS_DESTROY]
1005. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:735 (sofia/default/1246167409@103.240.35.46:60464) Running State Change CS_DESTROY (Cur 1 Tot 384)
1006. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1246167409@103.240.35.46:60464) State DESTROY
1007. 2022-07-12 11:17:35.136078 78.03% [DEBUG] mod_sofia.c:379 sofia/default/1246167409@103.240.35.46:60464 SOFIA DESTROY
1008. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:175 sofia/default/1246167409@103.240.35.46:60464 Standard DESTROY
1009. 2022-07-12 11:17:35.136078 78.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/1246167409@103.240.35.46:60464) State DESTROY going to sleep
1010. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:168 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard REPORTING, cause: NORMAL_CLEARING
1011. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:932 (sofia/default/4010463821@alpha.astppbilling.org:5060) State REPORTING going to sleep
1012. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:607 (sofia/default/4010463821@alpha.astppbilling.org:5060) State Change CS_REPORTING -> CS_DESTROY
1013. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_session.c:1753 Session 380 (sofia/default/4010463821@alpha.astppbilling.org:5060) Locked, Waiting on external entities
1014. 2022-07-12 11:17:35.176077 78.03% [NOTICE] switch_core_session.c:1771 Session 380 (sofia/default/4010463821@alpha.astppbilling.org:5060) Ended
1015. 2022-07-12 11:17:35.176077 78.03% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/4010463821@alpha.astppbilling.org:5060 [CS_DESTROY]
1016. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:735 (sofia/default/4010463821@alpha.astppbilling.org:5060) Running State Change CS_DESTROY (Cur 0 Tot 384)
1017. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4010463821@alpha.astppbilling.org:5060) State DESTROY
1018. 2022-07-12 11:17:35.176077 78.03% [DEBUG] mod_sofia.c:379 sofia/default/4010463821@alpha.astppbilling.org:5060 SOFIA DESTROY
1019. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:175 sofia/default/4010463821@alpha.astppbilling.org:5060 Standard DESTROY
1020. 2022-07-12 11:17:35.176077 78.03% [DEBUG] switch_core_state_machine.c:745 (sofia/default/4010463821@alpha.astppbilling.org:5060) State DESTROY going to sleep
1021. 2022-07-12 11:17:35.436089 76.33% [DEBUG] switch_scheduler.c:147 Deleting task 48 switch_ivr_schedule_hangup (98a4409d-27f7-49eb-8d32-46969f32f06f)
1022. freeswitch@devangnewdeb11>
1023. freeswitch@devangnewdeb11>
1024. freeswitch@devangnewdeb11> /exit
1025.  
1026. root@devangnewdeb11:/#