1. 2023-06-29 14:55:47.110377 15.93% [NOTICE] switch_core_session.c:1771 Session 28998 (sofia/lcr/7687026614@WandL882.voice.upstreamconnect.co.za) Ended
2. 2023-06-29 14:55:47.110377 15.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/7687026614@WandL882.voice.upstreamconnect.co.za [CS_DESTROY]
3. 2023-06-29 14:55:47.205740 15.93% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [207@Crestway731.voice.upstreamconnect.co.za] from ip 102.182.12.207
4. 2023-06-29 14:55:47.365735 15.93% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [208@Crestway731.voice.upstreamconnect.co.za] from ip 102.182.12.207
5. 2023-06-29 14:55:48.205727 15.97% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/+27448016300@169.239.8.50 [78a4ea89-d277-48ed-ae64-9542dc98e72d]
6. 2023-06-29 14:55:48.205727 15.97% [INFO] sofia.c:10462 sofia/lcr/+27448016300@169.239.8.50 receiving invite from 169.239.8.50:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 792861433d9253597c60da9d2fd87367@196.6.122.5:5060~2o
7. 2023-06-29 14:55:48.205727 15.97% [INFO] mod_dialplan_xml.c:639 Processing 0448016300 <+27448016300>->27448745140 in context default
8. 2023-06-29 14:55:48.225824 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
9. 2023-06-29 14:55:48.225824 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27448745140
10. 2023-06-29 14:55:48.225824 15.97% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
11. 2023-06-29 14:55:48.225824 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 0448016300 / +27448016300
12. 2023-06-29 14:55:48.225824 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
13. 2023-06-29 14:55:48.225824 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
14. 2023-06-29 14:55:48.225824 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
15. 2023-06-29 14:55:48.245762 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
16. 2023-06-29 14:55:48.245762 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
17. 2023-06-29 14:55:48.245762 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27448745140
18. 2023-06-29 14:55:48.245762 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
19. 2023-06-29 14:55:48.245762 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
20. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
21. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
22. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
23. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
24. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
25. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
26. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
27. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
28. 2023-06-29 14:55:48.265895 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
29. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
30. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
31. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 68
32. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
33. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
34. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
35. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27448745140
36. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
37. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
38. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
39. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
40. 2023-06-29 14:55:48.265895 15.97% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
41. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
42. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
43. 2023-06-29 14:55:48.265895 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
44. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
45. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
46. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
47. 2023-06-29 14:55:48.285780 15.97% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : DTM779.voice.upstreamconnect.co.za
48. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
49. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
50. 2023-06-29 14:55:48.285780 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
51. 2023-06-29 14:55:48.305716 15.97% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : DTM779.voice.upstreamconnect.co.za
52. 2023-06-29 14:55:48.325704 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP ID : 790
53. 2023-06-29 14:55:48.345712 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] Call Forwarding Flag : 1
54. 2023-06-29 14:55:48.345712 15.97% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP Call Forwarding Disable
55. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(effective_destination_number=27448745140)
56. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(bridge_pre_execute_bleg_app=sched_hangup)
57. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
58. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(callstart=2023-06-29 12:55:48)
59. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(hangup_after_bridge=true)
60. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(continue_on_fail=TRUE)
61. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(account_id=4)
62. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(parent_id=0)
63. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(entity_id=3)
64. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(call_processed=internal)
65. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(call_direction=inbound)
66. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(accountname=Switch)
67. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(t38_passthru=true)
68. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(fax_enable_t38=true)
69. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(fax_enable_t38_request=true)
70. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(filename1=astpp.pbx.lua)
71. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(origination_rates_did=ID:68|CODE:^27448745140.*|DESTINATION:27448745140|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:171|ACCID:786)
72. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(origination_rates=0)
73. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(original_caller_id_name=0448016300)
74. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(original_caller_id_number=+27448016300)
75. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(is_recording=1)
76. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(media_bug_answer_req=true)
77. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(RECORD_STEREO=true)
78. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(record_sample_rate=8000)
79. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/78a4ea89-d277-48ed-ae64-9542dc98e72dDID-LOCAL_786.wav)
80. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(effective_caller_id_name=0448016300)
81. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(effective_caller_id_number=+27448016300)
82. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(receiver_accid=786)
83. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(domain_name=DTM779.voice.upstreamconnect.co.za)
84. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(sip_to_host=DTM779.voice.upstreamconnect.co.za)
85. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(presence_data=x|||DTM(3303416355)|||||||||DID|||169.239.8.50)
86. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(call_type=0)
87. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(calltype=DID-LOCAL)
88. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 db(insert/pickup_any_group_/3303416355/78a4ea89-d277-48ed-ae64-9542dc98e72d)
89. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(dialed_extension=transfer_call)
90. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(dialed_extension=transfer_call)
91. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
92. 2023-06-29 14:55:48.445695 15.97% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
93. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
94. 2023-06-29 14:55:48.445695 15.97% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
95. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(hangup_after_bridge=true)
96. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(did_user_domain=DTM779.voice.upstreamconnect.co.za)
97. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 db(insert/pickup_any_group_/3303416355/78a4ea89-d277-48ed-ae64-9542dc98e72d)
98. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(dialed_extension=transfer_call)
99. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 export(dialed_extension=transfer_call)
100. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
101. 2023-06-29 14:55:48.445695 15.97% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
102. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
103. 2023-06-29 14:55:48.445695 15.97% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
104. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(transfer_ringback=local_stream://moh)
105. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(hangup_after_bridge=true)
106. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 bridge({sip_invite_params=user=LOCAL,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=786}[leg_timeout=60 ] user/3303416355@DTM779.voice.upstreamconnect.co.za)
107. 2023-06-29 14:55:48.485924 15.97% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
108. 2023-06-29 14:55:48.485924 15.97% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [user] cause: [USER_NOT_REGISTERED]
109. 2023-06-29 14:55:48.485924 15.97% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: USER_NOT_REGISTERED
110. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(on_busy_flag=1)
111. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(on_busy_destination=)
112. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(no_answer_flag=1)
113. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(no_answer_destination=)
114. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(not_register_flag=1)
115. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(not_register_destination=)
116. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(opensips_flag=1)
117. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(variable_sip_to_host=DTM779.voice.upstreamconnect.co.za)
118. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(opensips_domain=7.0.0.)
119. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(leg_timeout=60)
120. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(userinfo_id=786)
121. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(sip_destination_number=3303416355)
122. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(did_number=3303416355)
123. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(user_domain=DTM779.voice.upstreamconnect.co.za)
124. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 lua(astpp/lib/pbx/astpp-sipdevice-routing.lua)
125. 2023-06-29 14:55:48.505705 15.97% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] This Is Last Dispositions USER_NOT_REGISTERED This Is SIP Number : 3303416355
126. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 answer()
127. 2023-06-29 14:55:48.505705 15.97% [NOTICE] mod_dptools.c:1419 Channel [sofia/lcr/+27448016300@169.239.8.50] has been answered
128. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 record_session(/var/lib/freeswitch/recordings/78a4ea89-d277-48ed-ae64-9542dc98e72dDID-LOCAL_786.wav)
129. 2023-06-29 14:55:48.525700 15.97% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] Dialed User For Voicemail :3303416355
130. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 voicemail(default DTM779.voice.upstreamconnect.co.za 3303416355)
131. 2023-06-29 14:55:48.545699 15.97% [WARNING] mod_voicemail.c:3455 User [3303416355@DTM779.voice.upstreamconnect.co.za] have voicemail disabled
132. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(on_busy_flag=1)
133. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(on_busy_destination=)
134. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(no_answer_flag=1)
135. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(no_answer_destination=)
136. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(not_register_flag=1)
137. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(not_register_destination=)
138. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(opensips_flag=1)
139. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(variable_sip_to_host=DTM779.voice.upstreamconnect.co.za)
140. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(opensips_domain=7.0.0.)
141. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(leg_timeout=60)
142. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(userinfo_id=786)
143. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(sip_destination_number=3303416355)
144. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(destination_number=27448745140)
145. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(did_number=27448745140)
146. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 set(user_domain=DTM779.voice.upstreamconnect.co.za)
147. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 lua(astpp/lib/pbx/astpp-sipdevice-routing.lua)
148. 2023-06-29 14:55:48.565695 15.97% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] This Is Last Dispositions USER_NOT_REGISTERED This Is SIP Number : 3303416355
149. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 answer()
150. 2023-06-29 14:55:48.565695 15.97% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] Dialed User For Voicemail :3303416355
151. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 voicemail(default DTM779.voice.upstreamconnect.co.za 3303416355)
152. 2023-06-29 14:55:48.605697 15.97% [WARNING] mod_voicemail.c:3455 User [3303416355@DTM779.voice.upstreamconnect.co.za] have voicemail disabled
153. EXECUTE [depth=0] sofia/lcr/+27448016300@169.239.8.50 hangup(USER_NOT_REGISTERED)
154. 2023-06-29 14:55:48.605697 15.97% [NOTICE] mod_dptools.c:1387 Hangup sofia/lcr/+27448016300@169.239.8.50 [CS_EXECUTE] [USER_NOT_REGISTERED]
155. 2023-06-29 14:55:48.625698 15.97% [INFO] mod_json_cdr.c:271 Process [78a4ea89-d277-48ed-ae64-9542dc98e72d.cdr.json]
156. 2023-06-29 14:55:48.685717 16.37% [NOTICE] switch_core_session.c:1771 Session 29074 (sofia/lcr/+27448016300@169.239.8.50) Ended
157. 2023-06-29 14:55:48.685717 16.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/+27448016300@169.239.8.50 [CS_DESTROY]
158. 2023-06-29 14:55:49.125703 16.37% [NOTICE] sofia.c:8683 Channel [sofia/lcr/2151888296@102.135.146.5:5060] has been answered
159. EXECUTE [depth=1] sofia/lcr/2151888296@102.135.146.5:5060 record_session(/var/lib/freeswitch/recordings/dcba6cc0-c9fa-4248-952d-038213d8620dDID-LOCAL_784.wav)
160. 2023-06-29 14:55:49.145786 16.37% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/lcr/27871883119@169.239.8.50] has been answered
161. EXECUTE [depth=0] sofia/lcr/27871883119@169.239.8.50 record_session(/var/lib/freeswitch/recordings/dcba6cc0-c9fa-4248-952d-038213d8620dDID-LOCAL_784.wav)
162. EXECUTE [depth=0] sofia/lcr/2151888296@102.135.146.5:5060 sched_hangup(+3600 normal_clearing)
163. 2023-06-29 14:55:49.465742 16.37% [NOTICE] sofia.c:8738 Hangup sofia/lcr/27217537468@169.239.11.181 [CS_EXECUTE] [ORIGINATOR_CANCEL]
164. 2023-06-29 14:55:49.465742 16.37% [NOTICE] switch_ivr_bridge.c:1025 Hangup sofia/default/27124064430 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
165. 2023-06-29 14:55:49.465742 16.37% [INFO] mod_json_cdr.c:271 Process [ff50d64f-6903-4f44-8912-612dd7826e60.cdr.json]
166. 2023-06-29 14:55:49.465742 16.37% [INFO] mod_json_cdr.c:271 Process [280231a0-f5a4-4d26-b87d-b8e78d632472.cdr.json]
167. 2023-06-29 14:55:49.485703 16.37% [NOTICE] switch_core_session.c:1771 Session 29065 (sofia/default/27124064430) Ended
168. 2023-06-29 14:55:49.485703 16.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/27124064430 [CS_DESTROY]
169. 2023-06-29 14:55:49.545701 16.37% [NOTICE] switch_core_session.c:1771 Session 29063 (sofia/lcr/27217537468@169.239.11.181) Ended
170. 2023-06-29 14:55:49.545701 16.37% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/27217537468@169.239.11.181 [CS_DESTROY]
171. 2023-06-29 14:55:51.085796 17.77% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [5307795712@SA712.voice.upstreamconnect.co.za] from ip 169.0.116.158
172. 2023-06-29 14:55:53.385949 19.60% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [update sip_subscriptions set version=version+1 where hostname='astpp' and profile_name='default' and sip_subscriptions.event != 'line-seize' and sip_subscriptions.proto='sip' and (event='presence' or event='dialog') and sub_to_user='200' and (sub_to_host='Crestway731.voice.upstreamconnect.co.za' or sub_to_host='154.0.2.205' or sub_to_host='154.0.2.205' or presence_hosts like '%Crestway731.voice.upstreamconnect.co.za%') and (sip_subscriptions.profile_name = 'default' or presence_hosts like '%Crestway731.voice.upstreamconnect.co.za%')]
173. 2023-06-29 14:55:53.385949 19.60% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8002183027@Vallei671.voice.upstreamconnect.co.za] from ip 192.143.21.181
174. 2023-06-29 14:55:53.625712 19.60% [NOTICE] switch_core_session.c:1771 Session 28856 (sofia/lcr/9896601891@Dr922.voice.upstreamconnect.co.za) Ended
175. 2023-06-29 14:55:53.625712 19.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/9896601891@Dr922.voice.upstreamconnect.co.za [CS_DESTROY]
176. 2023-06-29 14:55:53.785786 20.37% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8175437451@Paarl728.voice.upstreamconnect.co.za] from ip 102.135.148.15
177. 2023-06-29 14:55:54.305700 20.37% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8390905907@Tooth685.voice.upstreamconnect.co.za] from ip 45.221.82.31
178. 2023-06-29 14:55:54.745708 20.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'lcr' for [202@Knysna870.voice.upstreamconnect.co.za] from ip 102.22.72.83
179. 2023-06-29 14:55:55.105709 20.87% [INFO] switch_channel.c:527 RECV DTMF 3:2240
180. 2023-06-29 14:55:55.145714 20.87% [NOTICE] sofia.c:1065 Hangup sofia/lcr/27217537468@169.239.11.181 [CS_EXECUTE] [NORMAL_CLEARING]
181. 2023-06-29 14:55:55.145714 20.87% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/27832869562 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
182. 2023-06-29 14:55:55.145714 20.87% [INFO] mod_json_cdr.c:271 Process [c3fb47f7-0b97-4077-8714-02fe5b9df4b4.cdr.json]
183. 2023-06-29 14:55:55.145714 20.87% [INFO] mod_json_cdr.c:271 Process [dd559b19-9e3f-41b4-a9e4-a0ba6983644e.cdr.json]
184. 2023-06-29 14:55:55.185710 20.87% [NOTICE] switch_core_session.c:1771 Session 29025 (sofia/default/27832869562) Ended
185. 2023-06-29 14:55:55.185710 20.87% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/27832869562 [CS_DESTROY]
186. 2023-06-29 14:55:55.225706 20.87% [NOTICE] switch_core_session.c:1771 Session 29023 (sofia/lcr/27217537468@169.239.11.181) Ended
187. 2023-06-29 14:55:55.225706 20.87% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/27217537468@169.239.11.181 [CS_DESTROY]
188. 2023-06-29 14:55:55.325725 20.87% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/200@RAC718.voice.upstreamconnect.co.za [737cbab6-049b-4f51-b05a-7074d87c3406]
189. 2023-06-29 14:55:55.325725 20.87% [INFO] sofia.c:10462 sofia/lcr/200@RAC718.voice.upstreamconnect.co.za receiving invite from 102.135.148.92:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 0e6517d5-aca6-4820-8ccd-d094ff326e18
190. 2023-06-29 14:55:55.325725 20.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'lcr' for [00264002648938025@RAC718.voice.upstreamconnect.co.za] from ip 102.135.148.92
191. 2023-06-29 14:55:55.367689 20.87% [INFO] sofia.c:10462 sofia/lcr/200@RAC718.voice.upstreamconnect.co.za receiving invite from 102.135.148.92:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 0e6517d5-aca6-4820-8ccd-d094ff326e18
192. 2023-06-29 14:55:55.405707 20.87% [INFO] mod_dialplan_xml.c:639 Processing 200 <200>->00264002648938025 in context default
193. 2023-06-29 14:55:55.425710 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
194. 2023-06-29 14:55:55.425710 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 00264002648938025
195. 2023-06-29 14:55:55.425710 20.87% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
196. 2023-06-29 14:55:55.425710 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 5290810036 / 5290810036
197. 2023-06-29 14:55:55.425710 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
198. 2023-06-29 14:55:55.425710 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00264002648938025
199. 2023-06-29 14:55:55.425710 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 270264002648938025
200. 2023-06-29 14:55:55.425710 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
201. 2023-06-29 14:55:55.425710 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 5290810036]
202. 2023-06-29 14:55:55.445731 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
203. 2023-06-29 14:55:55.465722 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00264002648938025
204. 2023-06-29 14:55:55.465722 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 270264002648938025
205. 2023-06-29 14:55:55.465722 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
206. 2023-06-29 14:55:55.465722 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00264002648938025
207. 2023-06-29 14:55:55.465722 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 270264002648938025
208. 2023-06-29 14:55:55.505706 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 270264002648938025
209. 2023-06-29 14:55:55.505706 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
210. 2023-06-29 14:55:55.505706 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
211. 2023-06-29 14:55:55.525714 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] Get Destination Number (270264002648938025)!!!!
212. 2023-06-29 14:55:55.525714 20.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] After Append Ported Number : (270264002648938025)!!!!
213. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
214. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 725
215. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 5290810036
216. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 1834.6739
217. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
218. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
219. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 1
220. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
221. 2023-06-29 14:55:55.525714 20.87% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
222. 2023-06-29 14:55:55.525714 20.87% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Rates Not Found :
223. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za log(WARNING Accountcode 5290810036. Dialed number (270264002648938025)  origination rates not found!!)
224. 2023-06-29 14:55:55.546905 20.87% [WARNING] mod_dptools.c:1879 Accountcode 5290810036. Dialed number (270264002648938025)  origination rates not found!!
225. 2023-06-29 14:55:55.546905 20.87% [INFO] switch_core_session.c:2797 Sending early media
226. 2023-06-29 14:55:55.546905 20.87% [NOTICE] mod_sofia.c:2613 Pre-Answer sofia/lcr/200@RAC718.voice.upstreamconnect.co.za!
227. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za playback(/usr/share/freeswitch/sounds/en/us/callie/astpp-badphone.wav)
228. 2023-06-29 14:55:55.905703 21.53% [INFO] switch_rtp.c:7894 Auto Changing audio port from 192.168.35.253:10438 to 102.135.148.92:10438
229. 2023-06-29 14:55:56.585697 21.53% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [9394055161@KCGC765.voice.upstreamconnect.co.za] from ip 197.89.151.50
230. 2023-06-29 14:55:56.705693 22.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27210072905@169.239.8.50 [d4f88d76-ce80-4d94-ba6b-12a3d9db1016]
231. 2023-06-29 14:55:56.705693 22.47% [INFO] sofia.c:10462 sofia/default/27210072905@169.239.8.50 receiving invite from 169.239.8.50:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 21656576_96465601@169.239.8.29~2o
232. 2023-06-29 14:55:56.705693 22.47% [INFO] mod_dialplan_xml.c:639 Processing David <27210072905>->27215515033 in context default
233. 2023-06-29 14:55:56.705693 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
234. 2023-06-29 14:55:56.705693 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27215515033
235. 2023-06-29 14:55:56.705693 22.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
236. 2023-06-29 14:55:56.705693 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : David / 27210072905
237. 2023-06-29 14:55:56.705693 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
238. 2023-06-29 14:55:56.705693 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
239. 2023-06-29 14:55:56.705693 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
240. 2023-06-29 14:55:56.725700 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
241. 2023-06-29 14:55:56.725700 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
242. 2023-06-29 14:55:56.725700 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27215515033
243. 2023-06-29 14:55:56.725700 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
244. 2023-06-29 14:55:56.725700 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
245. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
246. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
247. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
248. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
249. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
250. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
251. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
252. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
253. 2023-06-29 14:55:56.745903 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
254. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
255. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
256. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 75
257. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
258. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
259. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
260. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27215515033
261. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
262. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
263. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
264. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
265. 2023-06-29 14:55:56.745903 22.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
266. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
267. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
268. 2023-06-29 14:55:56.745903 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
269. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
270. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
271. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
272. 2023-06-29 14:55:56.765714 22.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : De758.voice.upstreamconnect.co.za
273. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
274. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
275. 2023-06-29 14:55:56.765714 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
276. 2023-06-29 14:55:56.785702 22.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : De758.voice.upstreamconnect.co.za
277. 2023-06-29 14:55:56.805715 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP ID : 769
278. 2023-06-29 14:55:56.805715 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] Call Forwarding Flag : 1
279. 2023-06-29 14:55:56.805715 22.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP Call Forwarding Disable
280. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(effective_destination_number=27215515033)
281. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(bridge_pre_execute_bleg_app=sched_hangup)
282. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
283. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(callstart=2023-06-29 12:55:56)
284. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(hangup_after_bridge=true)
285. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(continue_on_fail=TRUE)
286. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(account_id=4)
287. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(parent_id=0)
288. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(entity_id=3)
289. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(call_processed=internal)
290. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(call_direction=inbound)
291. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(accountname=Switch)
292. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(t38_passthru=true)
293. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(fax_enable_t38=true)
294. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(fax_enable_t38_request=true)
295. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(filename1=astpp.pbx.lua)
296. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(origination_rates_did=ID:75|CODE:^27215515033.*|DESTINATION:27215515033|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:171|ACCID:765)
297. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(origination_rates=0)
298. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(original_caller_id_name=David)
299. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(original_caller_id_number=27210072905)
300. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(is_recording=1)
301. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(media_bug_answer_req=true)
302. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(RECORD_STEREO=true)
303. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(record_sample_rate=8000)
304. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/d4f88d76-ce80-4d94-ba6b-12a3d9db1016DID-LOCAL_765.wav)
305. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(effective_caller_id_name=David)
306. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(effective_caller_id_number=27210072905)
307. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(receiver_accid=765)
308. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(domain_name=De758.voice.upstreamconnect.co.za)
309. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(sip_to_host=De758.voice.upstreamconnect.co.za)
310. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(presence_data=x|||De(4489797435)|||||||||DID|||169.239.8.50)
311. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(call_type=0)
312. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(calltype=DID-LOCAL)
313. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 db(insert/pickup_any_group_/4489797435/d4f88d76-ce80-4d94-ba6b-12a3d9db1016)
314. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(dialed_extension=transfer_call)
315. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(dialed_extension=transfer_call)
316. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
317. 2023-06-29 14:55:56.905709 22.47% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
318. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
319. 2023-06-29 14:55:56.905709 22.47% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
320. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(hangup_after_bridge=true)
321. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(did_user_domain=De758.voice.upstreamconnect.co.za)
322. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 db(insert/pickup_any_group_/4489797435/d4f88d76-ce80-4d94-ba6b-12a3d9db1016)
323. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(dialed_extension=transfer_call)
324. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 export(dialed_extension=transfer_call)
325. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
326. 2023-06-29 14:55:56.905709 22.47% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
327. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
328. 2023-06-29 14:55:56.905709 22.47% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
329. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(transfer_ringback=local_stream://moh)
330. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 set(hangup_after_bridge=true)
331. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 bridge({sip_invite_params=user=LOCAL,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=765}[leg_timeout=60 ] user/4489797435@De758.voice.upstreamconnect.co.za)
332. 2023-06-29 14:55:56.925703 22.47% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/4489797435@192.168.8.139:5060 [2917c31e-94d7-4cf0-8a90-7227c6f32e47]
333. 2023-06-29 14:55:56.945700 22.47% [INFO] sofia_glue.c:1651 sofia/lcr/4489797435@192.168.8.139:5060 sending invite call-id: (null)
334. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(original_caller_id_name=27215103064)
335. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(original_caller_id_number=27215103064)
336. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(error_cdr=1)
337. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(callstart=2023-06-29 12:55:55)
338. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(account_id=725)
339. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(parent_id=0)
340. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(call_direction=outbound)
341. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(sip_ignore_remote_cause=true)
342. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(call_processed=internal)
343. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(effective_destination_number=270264002648938025)
344. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za set(last_bridge_hangup_cause=ORIGINATION_RATE_NOT_FOUND)
345. EXECUTE [depth=0] sofia/lcr/200@RAC718.voice.upstreamconnect.co.za hangup(ORIGINATION_RATE_NOT_FOUND)
346. 2023-06-29 14:55:56.985696 22.47% [NOTICE] mod_dptools.c:1387 Hangup sofia/lcr/200@RAC718.voice.upstreamconnect.co.za [CS_EXECUTE] [NORMAL_CLEARING]
347. 2023-06-29 14:55:57.005703 22.47% [INFO] mod_json_cdr.c:271 Process [737cbab6-049b-4f51-b05a-7074d87c3406.cdr.json]
348. 2023-06-29 14:55:57.025696 22.47% [NOTICE] switch_core_session.c:1771 Session 29075 (sofia/lcr/200@RAC718.voice.upstreamconnect.co.za) Ended
349. 2023-06-29 14:55:57.025696 22.47% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/200@RAC718.voice.upstreamconnect.co.za [CS_DESTROY]
350. 2023-06-29 14:55:57.225692 22.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8510769778@Stenden693.voice.upstreamconnect.co.za] from ip 172.23.110.253
351. 2023-06-29 14:55:58.185715 23.40% [NOTICE] sofia.c:7610 Ring-Ready sofia/lcr/4489797435@192.168.8.139:5060!
352. 2023-06-29 14:55:58.185715 23.40% [NOTICE] mod_sofia.c:2515 Ring-Ready sofia/default/27210072905@169.239.8.50!
353. 2023-06-29 14:55:58.205708 23.40% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/default/27210072905@169.239.8.50!
354. 2023-06-29 14:55:58.545703 23.40% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/27217537468@169.239.11.181 [2f79b491-7f16-49d0-836f-724a909421c6]
355. 2023-06-29 14:55:58.545703 23.40% [INFO] sofia.c:10462 sofia/lcr/27217537468@169.239.11.181 receiving invite from 169.239.11.181:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 11084289-3897032212-906023@UPC-MSX-1B.local
356. 2023-06-29 14:55:58.565709 23.40% [INFO] mod_dialplan_xml.c:639 Processing 27217537468 <27217537468>->660127838668278 in context default
357. 2023-06-29 14:55:58.585708 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
358. 2023-06-29 14:55:58.585708 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 660127838668278
359. 2023-06-29 14:55:58.605702 23.40% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
360. 2023-06-29 14:55:58.605702 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27217537468 / 27217537468
361. 2023-06-29 14:55:58.605702 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
362. 2023-06-29 14:55:58.605702 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
363. 2023-06-29 14:55:58.605702 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 660127838668278
364. 2023-06-29 14:55:58.605702 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 27838668278
365. 2023-06-29 14:55:58.605702 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 4141872635]
366. 2023-06-29 14:55:58.625777 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
367. 2023-06-29 14:55:58.625777 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
368. 2023-06-29 14:55:58.625777 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27838668278
369. 2023-06-29 14:55:58.625777 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
370. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
371. 2023-06-29 14:55:58.645705 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] Get Destination Number (27838668278)!!!!
372. 2023-06-29 14:55:58.645705 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] After Append Ported Number : (27838668278)!!!!
373. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
374. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 922
375. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 4141872635
376. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 52228.8163
377. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
378. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 8
379. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 1
380. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
381. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
382. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
383. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 218875
384. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
385. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
386. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.27000
387. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Mobile MTN
388. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
389. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 922
390. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 3
391. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
392. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
393. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your52228.8163 balance Accountid 922 !!!
394. 2023-06-29 14:55:58.645705 23.40% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 60 mins!
395. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
396. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate ID : 218875
397. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
398. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 63765
399. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^2783.*
400. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : SOUTH AFRICA
401. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
402. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
403. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^2783.*
404. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
405. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
406. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 3
407. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : iMobilityISBryanston
408. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
409. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : iMobilityTeracoIsand
410. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 907
411. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 100
412. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk Name : PrimaryTrunk
413. 2023-06-29 14:55:58.645705 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
414. 2023-06-29 14:55:58.665802 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
415. 2023-06-29 14:55:58.665802 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
416. 2023-06-29 14:55:58.665802 23.40% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : outbound
417. 2023-06-29 14:55:58.685715 24.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [6987188231@Hilpak776.voice.upstreamconnect.co.za] from ip 102.182.16.69
418. 2023-06-29 14:55:58.685715 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
419. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_destination_number=27838668278)
420. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_app=sched_hangup)
421. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
422. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(callstart=2023-06-29 12:55:58)
423. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(hangup_after_bridge=true)
424. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(continue_on_fail=TRUE)
425. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(account_id=922)
426. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(parent_id=0)
427. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(entity_id=0)
428. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_processed=internal)
429. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_direction=outbound)
430. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(accountname=LCR Local)
431. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(t38_passthru=true)
432. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38=true)
433. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38_request=true)
434. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(filename1=astpp.pbx.lua)
435. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(origination_rates=ID:218875|CODE:^2783.*|DESTINATION:Mobile MTN|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.27000|INC:1|INITIALBLOCK:1|RATEGROUP:8|MARKUP:0|CI:0|ACCID:922)
436. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_name=27217537468)
437. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_number=27217537468)
438. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_name=27217537468)
439. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_number=27217537468)
440. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(calltype=STANDARD)
441. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(termination_rates=ID:63765|CODE:^2783.*|DESTINATION:SOUTH AFRICA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.12000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:907)
442. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(trunk_id=3)
443. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(provider_id=907)
444. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(rate_flag=0)
445. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(force_trunk_flag=218875)
446. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=trunk_id=3)
447. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(intcall=0)
448. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(poesence_id=27838668278)
449. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=x|||LCR(4141872635)|||^2783.* // Mobile MTN // 0.27000 |||PrimaryTrunk // ^2783.* // SOUTH AFRICA // 0.12000 // trunk_id=3|||STD|||169.239.11.181)
450. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 limit(db iMobilityISBryanston gw_iMobilityISBryanston 100 )
451. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityISBryanston/27838668278)
452. 2023-06-29 14:55:58.725698 24.47% [ERR] mod_sofia.c:4813 Gateway 'iMobilityISBryanston' is down!
453. 2023-06-29 14:55:58.725698 24.47% [NOTICE] mod_sofia.c:5221 Close Channel N/A [CS_NEW]
454. 2023-06-29 14:55:58.725698 24.47% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [sofia] cause: [GATEWAY_DOWN]
455. 2023-06-29 14:55:58.725698 24.47% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: GATEWAY_DOWN
456. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityTeracoIsand/27838668278)
457. 2023-06-29 14:55:58.725698 24.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27838668278 [c53730c1-754f-40d7-9614-428185e02f8e]
458. 2023-06-29 14:55:58.725698 24.47% [INFO] sofia_glue.c:1651 sofia/default/27838668278 sending invite call-id: (null)
459. 2023-06-29 14:55:58.785697 24.47% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/27217537468@169.239.11.181 [29318954-945f-4654-85ca-73b4038a9781]
460. 2023-06-29 14:55:58.785697 24.47% [INFO] sofia.c:10462 sofia/lcr/27217537468@169.239.11.181 receiving invite from 169.239.11.181:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 11084290-3897032213-133350@UPC-MSX-1B.local
461. 2023-06-29 14:55:58.785697 24.47% [INFO] mod_dialplan_xml.c:639 Processing 27217537468 <27217537468>->660127317019901 in context default
462. 2023-06-29 14:55:58.785697 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
463. 2023-06-29 14:55:58.785697 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 660127317019901
464. 2023-06-29 14:55:58.785697 24.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
465. 2023-06-29 14:55:58.785697 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27217537468 / 27217537468
466. 2023-06-29 14:55:58.785697 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
467. 2023-06-29 14:55:58.805700 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
468. 2023-06-29 14:55:58.805700 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 660127317019901
469. 2023-06-29 14:55:58.805700 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 27317019901
470. 2023-06-29 14:55:58.805700 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 4141872635]
471. 2023-06-29 14:55:58.825696 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
472. 2023-06-29 14:55:58.825696 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
473. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27317019901
474. 2023-06-29 14:55:58.845723 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
475. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
476. 2023-06-29 14:55:58.845723 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] Get Destination Number (27317019901)!!!!
477. 2023-06-29 14:55:58.845723 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] After Append Ported Number : (27317019901)!!!!
478. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
479. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 922
480. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 4141872635
481. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 52228.8163
482. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
483. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 8
484. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 1
485. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
486. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
487. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
488. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 224999
489. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
490. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
491. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.20000
492. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Proper Telkom
493. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
494. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 922
495. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 3
496. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
497. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
498. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your52228.8163 balance Accountid 922 !!!
499. 2023-06-29 14:55:58.845723 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 60 mins!
500. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
501. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate ID : 224999
502. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
503. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 60462
504. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^2731701.*
505. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : SOUTH AFRICA
506. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
507. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
508. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^2731701.*
509. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
510. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
511. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 3
512. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : iMobilityISBryanston
513. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
514. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : iMobilityTeracoIsand
515. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 907
516. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 100
517. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk Name : PrimaryTrunk
518. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
519. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
520. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
521. 2023-06-29 14:55:58.845723 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : outbound
522. 2023-06-29 14:55:58.865694 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
523. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_destination_number=27317019901)
524. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_app=sched_hangup)
525. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
526. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(callstart=2023-06-29 12:55:58)
527. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(hangup_after_bridge=true)
528. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(continue_on_fail=TRUE)
529. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(account_id=922)
530. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(parent_id=0)
531. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(entity_id=0)
532. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_processed=internal)
533. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_direction=outbound)
534. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(accountname=LCR Local)
535. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(t38_passthru=true)
536. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38=true)
537. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38_request=true)
538. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(filename1=astpp.pbx.lua)
539. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(origination_rates=ID:224999|CODE:^2731701.*|DESTINATION:Proper Telkom|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:1|INITIALBLOCK:1|RATEGROUP:8|MARKUP:0|CI:0|ACCID:922)
540. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_name=27217537468)
541. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_number=27217537468)
542. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_name=27217537468)
543. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_number=27217537468)
544. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(calltype=STANDARD)
545. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(termination_rates=ID:60462|CODE:^2731701.*|DESTINATION:SOUTH AFRICA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:907)
546. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(trunk_id=3)
547. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(provider_id=907)
548. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(rate_flag=0)
549. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(force_trunk_flag=224999)
550. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=trunk_id=3)
551. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(intcall=0)
552. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(poesence_id=27317019901)
553. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=x|||LCR(4141872635)|||^2731701.* // Proper Telkom // 0.20000 |||PrimaryTrunk // ^2731701.* // SOUTH AFRICA // 0.09000 // trunk_id=3|||STD|||169.239.11.181)
554. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 limit(db iMobilityISBryanston gw_iMobilityISBryanston 100 )
555. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityISBryanston/27317019901)
556. 2023-06-29 14:55:58.885771 24.47% [ERR] mod_sofia.c:4813 Gateway 'iMobilityISBryanston' is down!
557. 2023-06-29 14:55:58.885771 24.47% [NOTICE] mod_sofia.c:5221 Close Channel N/A [CS_NEW]
558. 2023-06-29 14:55:58.885771 24.47% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [sofia] cause: [GATEWAY_DOWN]
559. 2023-06-29 14:55:58.885771 24.47% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: GATEWAY_DOWN
560. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityTeracoIsand/27317019901)
561. 2023-06-29 14:55:58.885771 24.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27317019901 [7eb36111-cb4e-4ddb-adbd-16456a27dffe]
562. 2023-06-29 14:55:58.885771 24.47% [INFO] sofia_glue.c:1651 sofia/default/27317019901 sending invite call-id: (null)
563. 2023-06-29 14:55:59.345699 24.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [6755942023@Premier911.voice.upstreamconnect.co.za] from ip 169.1.49.157
564. 2023-06-29 14:55:59.485774 24.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [3518189799@Settlers913.voice.upstreamconnect.co.za] from ip 102.182.121.111
565. 2023-06-29 14:55:59.485774 24.47% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27836592090@169.239.8.50 [7cb7ccf9-ec2f-45c0-beef-4683214f847b]
566. 2023-06-29 14:55:59.485774 24.47% [INFO] sofia.c:10462 sofia/default/27836592090@169.239.8.50 receiving invite from 169.239.8.50:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 21526538_100593603@169.239.8.23~2o
567. 2023-06-29 14:55:59.485774 24.47% [INFO] mod_dialplan_xml.c:639 Processing 27836592090 <27836592090>->27107450024 in context default
568. 2023-06-29 14:55:59.525728 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
569. 2023-06-29 14:55:59.525728 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27107450024
570. 2023-06-29 14:55:59.545760 24.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
571. 2023-06-29 14:55:59.545760 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27836592090 / 27836592090
572. 2023-06-29 14:55:59.545760 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
573. 2023-06-29 14:55:59.545760 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
574. 2023-06-29 14:55:59.545760 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
575. 2023-06-29 14:55:59.545760 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
576. 2023-06-29 14:55:59.565733 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
577. 2023-06-29 14:55:59.565733 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27107450024
578. 2023-06-29 14:55:59.565733 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
579. 2023-06-29 14:55:59.565733 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
580. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
581. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
582. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
583. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
584. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
585. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
586. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
587. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
588. 2023-06-29 14:55:59.585738 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
589. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
590. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
591. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 10
592. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
593. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
594. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
595. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27107450024
596. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
597. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
598. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
599. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
600. 2023-06-29 14:55:59.585738 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
601. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
602. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
603. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
604. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
605. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
606. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
607. 2023-06-29 14:55:59.585738 24.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : Trevor920.voice.upstreamconnect.co.za
608. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition Id : 29
609. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Failover Timecondition : [{"extensions":"#","no_answer_call_type":"12"}]
610. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition : [{"hour":"00-23","minute":"00-59","wday":"1-7","mday":"1-31","mon":"1-12","year":"2023-2023","extensions":"1022","no_answer_call_type":"11","priority":"1"}]
611. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Priority : 1
612. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str :  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2023-2023"
613. 2023-06-29 14:55:59.585738 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
614. 2023-06-29 14:55:59.605715 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
615. 2023-06-29 14:55:59.605715 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
616. 2023-06-29 14:55:59.605715 24.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : Trevor920.voice.upstreamconnect.co.za
617. 2023-06-29 14:55:59.605715 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] TimeCondition Id : 29
618. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(timezone=Africa/Johannesburg)
619. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(office_status=open)
620. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(extensions=103)
621. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(no_answer_call_type=11)
622. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 execute_extension(27107450024_open)
623. 2023-06-29 14:55:59.605715 24.47% [INFO] mod_dialplan_xml.c:639 Processing 27836592090 <27836592090>->27107450024_open in context default
624. 2023-06-29 14:55:59.625708 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
625. 2023-06-29 14:55:59.625708 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27107450024
626. 2023-06-29 14:55:59.625708 24.47% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
627. 2023-06-29 14:55:59.625708 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27836592090 / 27836592090
628. 2023-06-29 14:55:59.625708 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
629. 2023-06-29 14:55:59.625708 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
630. 2023-06-29 14:55:59.645756 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
631. 2023-06-29 14:55:59.645756 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
632. 2023-06-29 14:55:59.645756 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
633. 2023-06-29 14:55:59.645756 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27107450024
634. 2023-06-29 14:55:59.645756 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
635. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
636. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
637. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
638. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
639. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
640. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
641. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
642. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
643. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
644. 2023-06-29 14:55:59.665716 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
645. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
646. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
647. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 10
648. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
649. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
650. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
651. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27107450024
652. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
653. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
654. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
655. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
656. 2023-06-29 14:55:59.665716 24.47% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
657. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
658. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
659. 2023-06-29 14:55:59.665716 24.47% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
660. 2023-06-29 14:55:59.665716 24.47% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8291662620@Transcash674.voice.upstreamconnect.co.za] from ip 192.143.70.168
661. 2023-06-29 14:55:59.685705 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
662. 2023-06-29 14:55:59.685705 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
663. 2023-06-29 14:55:59.685705 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
664. 2023-06-29 14:55:59.685705 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : Trevor920.voice.upstreamconnect.co.za
665. 2023-06-29 14:55:59.685705 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition Id : 29
666. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Failover Timecondition : [{"extensions":"#","no_answer_call_type":"12"}]
667. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Timecondition : [{"hour":"00-23","minute":"00-59","wday":"1-7","mday":"1-31","mon":"1-12","year":"2023-2023","extensions":"1022","no_answer_call_type":"11","priority":"1"}]
668. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Priority : 1
669. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] Condition Str :  time-of-day= "00:00-23:59"  wday= "1-7"  mday= "1-31"  mon= "1-12"  year= "2023-2023"
670. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
671. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
672. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
673. 2023-06-29 14:55:59.705697 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : Trevor920.voice.upstreamconnect.co.za
674. 2023-06-29 14:55:59.705697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [TIME_COND] TimeCondition Id : 29
675. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(timezone=Africa/Johannesburg)
676. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(office_status=open)
677. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(extensions=103)
678. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(no_answer_call_type=11)
679. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] set(domain_name=Trevor920.voice.upstreamconnect.co.za)
680. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(domain_name=Trevor920.voice.upstreamconnect.co.za)
681. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] set(set_extensions=${extensions})
682. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(set_extensions=103)
683. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] set(no_answer_flag=true)
684. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(no_answer_flag=true)
685. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] set(no_answer_call_type=${no_answer_call_type})
686. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(no_answer_call_type=11)
687. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] set(no_answer_number=${extensions})
688. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 set(no_answer_number=103)
689. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_core_session.c:3095 Execute [depth=1] transfer(932 XML default)
690. EXECUTE [depth=1] sofia/default/27836592090@169.239.8.50 transfer(932 XML default)
691. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_ivr.c:2296 Transfer sofia/default/27836592090@169.239.8.50 to XML[932@default]
692. 2023-06-29 14:55:59.725694 24.67% [INFO] mod_dialplan_xml.c:639 Processing 27836592090 <27836592090>->932 in context default
693. 2023-06-29 14:55:59.725694 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
694. 2023-06-29 14:55:59.725694 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 932
695. 2023-06-29 14:55:59.725694 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
696. 2023-06-29 14:55:59.725694 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27836592090 / 27836592090
697. 2023-06-29 14:55:59.725694 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
698. 2023-06-29 14:55:59.745756 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
699. 2023-06-29 14:55:59.745756 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
700. 2023-06-29 14:55:59.745756 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
701. 2023-06-29 14:55:59.765726 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [ringgroup_no_answer_forward] User DomainTrevor920.voice.upstreamconnect.co.za
702. 2023-06-29 14:55:59.765726 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : 11
703. 2023-06-29 14:55:59.765726 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Type : Time Conditions
704. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(presence_data=x|||Trevor(8180102517)|||||||||DID|||169.239.8.50)
705. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(did_user_domain=154.0.2.205)
706. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(continue_on_fail=TRUE)
707. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(ignore_early_media=true)
708. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(no_answer_flag=false)
709. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(did_calltype=11)
710. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(did_extensions=103)
711. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(is_recording=1)
712. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(media_bug_answer_req=true)
713. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(RECORD_STEREO=true)
714. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(record_sample_rate=8000)
715. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/7cb7ccf9-ec2f-45c0-beef-4683214f847b.wav)
716. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(sip_destination_number=103)
717. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(sip_to_host=Trevor920.voice.upstreamconnect.co.za)
718. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(user_domain=Trevor920.voice.upstreamconnect.co.za)
719. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 db(insert/pickup_any_group_/103/7cb7ccf9-ec2f-45c0-beef-4683214f847b)
720. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 set(dialed_extension=transfer_call)
721. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(dialed_extension=transfer_call)
722. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
723. 2023-06-29 14:55:59.785789 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
724. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
725. 2023-06-29 14:55:59.785789 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
726. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 export(ring_group_transfer=true)
727. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 bridge(user/103@Trevor920.voice.upstreamconnect.co.za)
728. 2023-06-29 14:55:59.805708 24.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27825729830@169.239.8.50 [81a286df-f064-4dca-b0d3-dbb8fd21321e]
729. 2023-06-29 14:55:59.805708 24.67% [INFO] sofia.c:10462 sofia/default/27825729830@169.239.8.50 receiving invite from 169.239.8.50:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 21787047_92268552@169.239.8.24~2o
730. 2023-06-29 14:55:59.825704 24.67% [INFO] mod_dialplan_xml.c:639 Processing +27825729830 <27825729830>->27117928777 in context default
731. 2023-06-29 14:55:59.825704 24.67% [NOTICE] switch_channel.c:1123 New Channel sofia/default/103@172.16.0.2:56406 [d6db2976-7e8e-4c21-8949-800e383ff319]
732. 2023-06-29 14:55:59.825704 24.67% [INFO] sofia_glue.c:1651 sofia/default/103@172.16.0.2:56406 sending invite call-id: (null)
733. 2023-06-29 14:55:59.825704 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
734. 2023-06-29 14:55:59.825704 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27117928777
735. 2023-06-29 14:55:59.825704 24.67% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/27317019901!
736. 2023-06-29 14:55:59.845693 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
737. 2023-06-29 14:55:59.845693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : +27825729830 / 27825729830
738. 2023-06-29 14:55:59.845693 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
739. 2023-06-29 14:55:59.845693 24.67% [NOTICE] mod_sofia.c:2515 Ring-Ready sofia/lcr/27217537468@169.239.11.181!
740. 2023-06-29 14:55:59.845693 24.67% [NOTICE] sofia.c:7610 Ring-Ready sofia/default/103@172.16.0.2:56406!
741. 2023-06-29 14:55:59.845693 24.67% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/lcr/27217537468@169.239.11.181!
742. 2023-06-29 14:55:59.845693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
743. 2023-06-29 14:55:59.845693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
744. 2023-06-29 14:55:59.845693 24.67% [NOTICE] mod_sofia.c:2515 Ring-Ready sofia/default/27836592090@169.239.8.50!
745. 2023-06-29 14:55:59.845693 24.67% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/default/27836592090@169.239.8.50!
746. 2023-06-29 14:55:59.845693 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
747. 2023-06-29 14:55:59.865693 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
748. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27117928777
749. 2023-06-29 14:55:59.865693 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
750. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
751. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
752. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
753. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
754. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
755. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
756. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
757. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
758. 2023-06-29 14:55:59.865693 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
759. 2023-06-29 14:55:59.865693 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
760. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
761. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
762. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 43
763. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
764. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
765. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
766. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27117928777
767. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
768. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
769. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
770. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
771. 2023-06-29 14:55:59.885697 24.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
772. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
773. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
774. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
775. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
776. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
777. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
778. 2023-06-29 14:55:59.885697 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : Symbolic690.voice.upstreamconnect.co.za
779. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
780. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
781. 2023-06-29 14:55:59.885697 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
782. 2023-06-29 14:55:59.885697 24.67% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : Symbolic690.voice.upstreamconnect.co.za
783. 2023-06-29 14:55:59.945696 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP ID : 701
784. 2023-06-29 14:55:59.945696 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] Call Forwarding Flag : 1
785. 2023-06-29 14:55:59.945696 24.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP Call Forwarding Disable
786. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(effective_destination_number=27117928777)
787. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(bridge_pre_execute_bleg_app=sched_hangup)
788. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
789. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(callstart=2023-06-29 12:55:59)
790. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(hangup_after_bridge=true)
791. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(continue_on_fail=TRUE)
792. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(account_id=4)
793. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(parent_id=0)
794. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(entity_id=3)
795. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(call_processed=internal)
796. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(call_direction=inbound)
797. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(accountname=Switch)
798. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(t38_passthru=true)
799. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(fax_enable_t38=true)
800. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(fax_enable_t38_request=true)
801. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(filename1=astpp.pbx.lua)
802. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(origination_rates_did=ID:43|CODE:^27117928777.*|DESTINATION:27117928777|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:171|ACCID:697)
803. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(origination_rates=0)
804. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(original_caller_id_name=+27825729830)
805. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(original_caller_id_number=27825729830)
806. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(is_recording=1)
807. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(media_bug_answer_req=true)
808. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(RECORD_STEREO=true)
809. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(record_sample_rate=8000)
810. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/81a286df-f064-4dca-b0d3-dbb8fd21321eDID-LOCAL_697.wav)
811. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(effective_caller_id_name=+27825729830)
812. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(effective_caller_id_number=27825729830)
813. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(receiver_accid=697)
814. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(domain_name=Symbolic690.voice.upstreamconnect.co.za)
815. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(sip_to_host=Symbolic690.voice.upstreamconnect.co.za)
816. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(presence_data=x|||Symbolic(8998591630)|||||||||DID|||169.239.8.50)
817. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(call_type=0)
818. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(calltype=DID-LOCAL)
819. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 db(insert/pickup_any_group_/8998591630/81a286df-f064-4dca-b0d3-dbb8fd21321e)
820. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(dialed_extension=transfer_call)
821. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(dialed_extension=transfer_call)
822. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
823. 2023-06-29 14:56:00.045698 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
824. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
825. 2023-06-29 14:56:00.045698 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
826. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(hangup_after_bridge=true)
827. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(did_user_domain=Symbolic690.voice.upstreamconnect.co.za)
828. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 db(insert/pickup_any_group_/8998591630/81a286df-f064-4dca-b0d3-dbb8fd21321e)
829. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(dialed_extension=transfer_call)
830. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 export(dialed_extension=transfer_call)
831. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
832. 2023-06-29 14:56:00.045698 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
833. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
834. 2023-06-29 14:56:00.045698 24.67% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
835. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(transfer_ringback=local_stream://moh)
836. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 set(hangup_after_bridge=true)
837. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 bridge({sip_invite_params=user=LOCAL,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=697}[leg_timeout=60 ] user/8998591630@Symbolic690.voice.upstreamconnect.co.za)
838. 2023-06-29 14:56:00.085697 24.67% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/8998591630@192.168.49.253:5060 [3c1495fc-eab9-42ad-ae49-24acdacedf0f]
839. 2023-06-29 14:56:00.085697 24.67% [INFO] sofia_glue.c:1651 sofia/lcr/8998591630@192.168.49.253:5060 sending invite call-id: (null)
840. 2023-06-29 14:56:00.231537 24.67% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [2660642901@Motors919.voice.upstreamconnect.co.za] from ip 102.135.148.84
841. 2023-06-29 14:56:00.325695 24.67% [NOTICE] sofia.c:7610 Ring-Ready sofia/lcr/8998591630@192.168.49.253:5060!
842. 2023-06-29 14:56:00.325695 24.67% [NOTICE] mod_sofia.c:2515 Ring-Ready sofia/default/27825729830@169.239.8.50!
843. 2023-06-29 14:56:00.325695 24.67% [NOTICE] switch_ivr_originate.c:565 Ring Ready sofia/default/27825729830@169.239.8.50!
844. 2023-06-29 14:56:00.709878 24.87% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/100@1.1.1.1 [ec7eaba3-021c-4cd8-b3c8-e0f806b75c1f]
845. 2023-06-29 14:56:00.709878 24.87% [INFO] sofia.c:10462 sofia/lcr/100@1.1.1.1 receiving invite from 185.166.153.205:59917 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 801201047282847871610169
846. 2023-06-29 14:56:00.709878 24.87% [WARNING] sofia_reg.c:1861 SIP auth challenge (INVITE) on sofia profile 'lcr' for [100@1.1.1.1] from ip 185.166.153.205
847. 2023-06-29 14:56:01.585694 24.87% [NOTICE] sofia.c:8683 Channel [sofia/default/103@172.16.0.2:56406] has been answered
848. EXECUTE [depth=1] sofia/default/103@172.16.0.2:56406 record_session(/var/lib/freeswitch/recordings/7cb7ccf9-ec2f-45c0-beef-4683214f847b.wav)
849. 2023-06-29 14:56:01.606159 24.87% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/27836592090@169.239.8.50] has been answered
850. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 record_session(/var/lib/freeswitch/recordings/7cb7ccf9-ec2f-45c0-beef-4683214f847b.wav)
851. 2023-06-29 14:56:01.866719 25.60% [INFO] switch_rtp.c:7894 Auto Changing audio port from 172.16.0.2:4008 to 104.28.197.140:27268
852. 2023-06-29 14:56:02.465746 25.60% [NOTICE] sofia.c:7512 Pre-Answer sofia/default/27838668278!
853. 2023-06-29 14:56:02.465746 25.60% [INFO] switch_ivr_originate.c:3841 Sending early media
854. 2023-06-29 14:56:02.485912 25.60% [NOTICE] mod_sofia.c:2613 Pre-Answer sofia/lcr/27217537468@169.239.11.181!
855. EXECUTE [depth=0] sofia/default/27838668278 sched_hangup(+3600 normal_clearing)
856. 2023-06-29 14:56:02.945693 25.83% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [9710092887@Roodezandt934.voice.upstreamconnect.co.za] from ip 213.172.130.235
857. 2023-06-29 14:56:04.745727 26.23% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/27217537468@169.239.11.181 [4914ee8f-fe61-44dd-ba6f-1c07d40264dd]
858. 2023-06-29 14:56:04.745727 26.23% [INFO] sofia.c:10462 sofia/lcr/27217537468@169.239.11.181 receiving invite from 169.239.11.181:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 11084295-3897032219-105652@UPC-MSX-1B.local
859. 2023-06-29 14:56:04.745727 26.23% [INFO] mod_dialplan_xml.c:639 Processing 27217537468 <27217537468>->660127124064430 in context default
860. 2023-06-29 14:56:04.765700 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
861. 2023-06-29 14:56:04.765700 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 660127124064430
862. 2023-06-29 14:56:04.765700 26.23% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
863. 2023-06-29 14:56:04.765700 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27217537468 / 27217537468
864. 2023-06-29 14:56:04.765700 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
865. 2023-06-29 14:56:04.765700 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
866. 2023-06-29 14:56:04.765700 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 660127124064430
867. 2023-06-29 14:56:04.765700 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 27124064430
868. 2023-06-29 14:56:04.765700 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 4141872635]
869. 2023-06-29 14:56:04.785884 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
870. 2023-06-29 14:56:04.785884 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
871. 2023-06-29 14:56:04.805720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27124064430
872. 2023-06-29 14:56:04.805720 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
873. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
874. 2023-06-29 14:56:04.825720 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] Get Destination Number (27124064430)!!!!
875. 2023-06-29 14:56:04.825720 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [PORTED_NUMBER] After Append Ported Number : (27124064430)!!!!
876. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
877. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 922
878. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 4141872635
879. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 52228.8163
880. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
881. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 8
882. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 1
883. 2023-06-29 14:56:04.825720 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
884. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
885. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
886. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 223339
887. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
888. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
889. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.20000
890. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Proper Telkom
891. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 0
892. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 922
893. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 3
894. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
895. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
896. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your52228.8163 balance Accountid 922 !!!
897. 2023-06-29 14:56:04.845707 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 60 mins!
898. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
899. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [DIALPLAN] User Rate ID : 223339
900. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
901. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 57470
902. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^2712406.*
903. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : SOUTH AFRICA
904. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
905. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
906. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^2712406.*
907. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
908. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
909. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 3
910. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : iMobilityISBryanston
911. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
912. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway : iMobilityTeracoIsand
913. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 907
914. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 100
915. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk Name : PrimaryTrunk
916. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
917. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
918. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
919. 2023-06-29 14:56:04.845707 26.23% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : outbound
920. 2023-06-29 14:56:04.865749 26.23% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
921. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_destination_number=27124064430)
922. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_app=sched_hangup)
923. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
924. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(callstart=2023-06-29 12:56:04)
925. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(hangup_after_bridge=true)
926. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(continue_on_fail=TRUE)
927. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(account_id=922)
928. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(parent_id=0)
929. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(entity_id=0)
930. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_processed=internal)
931. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(call_direction=outbound)
932. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(accountname=LCR Local)
933. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(t38_passthru=true)
934. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38=true)
935. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(fax_enable_t38_request=true)
936. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(filename1=astpp.pbx.lua)
937. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(origination_rates=ID:223339|CODE:^2712406.*|DESTINATION:Proper Telkom|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.20000|INC:1|INITIALBLOCK:1|RATEGROUP:8|MARKUP:0|CI:0|ACCID:922)
938. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_name=27217537468)
939. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(original_caller_id_number=27217537468)
940. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_name=27217537468)
941. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(effective_caller_id_number=27217537468)
942. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(calltype=STANDARD)
943. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(termination_rates=ID:57470|CODE:^2712406.*|DESTINATION:SOUTH AFRICA|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.09000|INC:0|INITIALBLOCK:0|TRUNK:3|PROVIDER:907)
944. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(trunk_id=3)
945. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(provider_id=907)
946. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(rate_flag=0)
947. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(force_trunk_flag=223339)
948. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=trunk_id=3)
949. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 set(intcall=0)
950. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(poesence_id=27124064430)
951. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 export(presence_data=x|||LCR(4141872635)|||^2712406.* // Proper Telkom // 0.20000 |||PrimaryTrunk // ^2712406.* // SOUTH AFRICA // 0.09000 // trunk_id=3|||STD|||169.239.11.181)
952. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 limit(db iMobilityISBryanston gw_iMobilityISBryanston 100 )
953. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityISBryanston/27124064430)
954. 2023-06-29 14:56:04.888933 26.23% [ERR] mod_sofia.c:4813 Gateway 'iMobilityISBryanston' is down!
955. 2023-06-29 14:56:04.888933 26.23% [NOTICE] mod_sofia.c:5221 Close Channel N/A [CS_NEW]
956. 2023-06-29 14:56:04.888933 26.23% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [sofia] cause: [GATEWAY_DOWN]
957. 2023-06-29 14:56:04.888933 26.23% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: GATEWAY_DOWN
958. EXECUTE [depth=0] sofia/lcr/27217537468@169.239.11.181 bridge([leg_timeout=30,absolute_codec_string=^^:G729:PCMA]sofia/gateway/iMobilityTeracoIsand/27124064430)
959. 2023-06-29 14:56:04.888933 26.23% [NOTICE] switch_channel.c:1123 New Channel sofia/default/27124064430 [e17154a5-4a89-4612-a948-1108f55c9ba8]
960. 2023-06-29 14:56:04.888933 26.23% [INFO] sofia_glue.c:1651 sofia/default/27124064430 sending invite call-id: (null)
961. 2023-06-29 14:56:04.945704 26.23% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'default' for [1326004439@Vue663.voice.upstreamconnect.co.za] from ip 192.143.52.198
962. 2023-06-29 14:56:05.525693 26.23% [NOTICE] sofia.c:1065 Hangup sofia/default/27218537322 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
963. 2023-06-29 14:56:05.525693 26.23% [INFO] mod_json_cdr.c:271 Process [d213cfc9-2ba5-467c-a0ca-3e4858c498a8.cdr.json]
964. 2023-06-29 14:56:05.546004 26.23% [NOTICE] switch_ivr_bridge.c:1944 Hangup sofia/lcr/135@DTM782.voice.upstreamconnect.co.za [CS_EXECUTE] [NORMAL_CLEARING]
965. 2023-06-29 14:56:05.565700 26.23% [INFO] mod_json_cdr.c:271 Process [6494ef26-91de-47ea-b968-e5c8561f026f.cdr.json]
966. 2023-06-29 14:56:05.565700 26.23% [NOTICE] switch_core_session.c:1771 Session 28869 (sofia/default/27218537322) Ended
967. 2023-06-29 14:56:05.565700 26.23% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/27218537322 [CS_DESTROY]
968. 2023-06-29 14:56:05.665737 26.23% [NOTICE] switch_core_session.c:1771 Session 28867 (sofia/lcr/135@DTM782.voice.upstreamconnect.co.za) Ended
969. 2023-06-29 14:56:05.665737 26.23% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/135@DTM782.voice.upstreamconnect.co.za [CS_DESTROY]
970. 2023-06-29 14:56:05.785696 25.90% [NOTICE] sofia.c:8683 Channel [sofia/lcr/4489797435@192.168.8.139:5060] has been answered
971. EXECUTE [depth=1] sofia/lcr/4489797435@192.168.8.139:5060 record_session(/var/lib/freeswitch/recordings/d4f88d76-ce80-4d94-ba6b-12a3d9db1016DID-LOCAL_765.wav)
972. 2023-06-29 14:56:05.805702 25.90% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/27210072905@169.239.8.50] has been answered
973. EXECUTE [depth=0] sofia/default/27210072905@169.239.8.50 record_session(/var/lib/freeswitch/recordings/d4f88d76-ce80-4d94-ba6b-12a3d9db1016DID-LOCAL_765.wav)
974. 2023-06-29 14:56:05.825700 25.90% [NOTICE] sofia.c:8738 Hangup sofia/lcr/27871883119@169.239.8.50 [CS_EXECUTE] [NORMAL_UNSPECIFIED]
975. 2023-06-29 14:56:05.845796 25.90% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/lcr/2151888296@102.135.146.5:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
976. 2023-06-29 14:56:05.865701 25.90% [INFO] mod_json_cdr.c:271 Process [aee57759-86f5-4d43-843d-07e2a41642f2.cdr.json]
977. 2023-06-29 14:56:05.865701 25.90% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [update sip_subscriptions set version=version+1 where hostname='astpp' and profile_name='default' and sip_subscriptions.event != 'line-seize' and sip_subscriptions.proto='sip' and (event='presence' or event='dialog') and sub_to_user='2151888296' and (sub_to_host='Durbanville777.voice.upstreamconnect.co.za' or sub_to_host='154.0.2.205' or sub_to_host='154.0.2.205' or presence_hosts like '%Durbanville777.voice.upstreamconnect.co.za%') and (sip_subscriptions.profile_name = 'default' or presence_hosts like '%Durbanville777.voice.upstreamconnect.co.za%')]
978. 2023-06-29 14:56:05.865701 25.90% [INFO] mod_json_cdr.c:271 Process [d8f922a4-2285-4c43-b095-86dd39b32876.cdr.json]
979. 2023-06-29 14:56:05.910406 25.90% [NOTICE] switch_core_session.c:1771 Session 29058 (sofia/lcr/2151888296@102.135.146.5:5060) Ended
980. 2023-06-29 14:56:05.910406 25.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/2151888296@102.135.146.5:5060 [CS_DESTROY]
981. 2023-06-29 14:56:05.945752 25.90% [NOTICE] switch_core_session.c:1771 Session 29057 (sofia/lcr/27871883119@169.239.8.50) Ended
982. 2023-06-29 14:56:05.945752 25.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/27871883119@169.239.8.50 [CS_DESTROY]
983. EXECUTE [depth=0] sofia/lcr/4489797435@192.168.8.139:5060 sched_hangup(+3600 normal_clearing)
984. 2023-06-29 14:56:06.145718 25.90% [INFO] switch_rtp.c:7894 Auto Changing audio port from 192.168.8.139:12176 to 192.143.57.42:12176
985. 2023-06-29 14:56:06.745705 25.73% [NOTICE] sofia.c:7512 Pre-Answer sofia/default/27124064430!
986. 2023-06-29 14:56:06.765700 25.73% [INFO] switch_ivr_originate.c:3841 Sending early media
987. 2023-06-29 14:56:06.765700 25.73% [NOTICE] mod_sofia.c:2613 Pre-Answer sofia/lcr/27217537468@169.239.11.181!
988. 2023-06-29 14:56:06.945702 25.73% [NOTICE] sofia.c:8683 Channel [sofia/lcr/8998591630@192.168.49.253:5060] has been answered
989. EXECUTE [depth=1] sofia/lcr/8998591630@192.168.49.253:5060 record_session(/var/lib/freeswitch/recordings/81a286df-f064-4dca-b0d3-dbb8fd21321eDID-LOCAL_697.wav)
990. 2023-06-29 14:56:06.965719 25.73% [NOTICE] switch_ivr_originate.c:3834 Channel [sofia/default/27825729830@169.239.8.50] has been answered
991. EXECUTE [depth=0] sofia/default/27825729830@169.239.8.50 record_session(/var/lib/freeswitch/recordings/81a286df-f064-4dca-b0d3-dbb8fd21321eDID-LOCAL_697.wav)
992. EXECUTE [depth=0] sofia/default/27124064430 sched_hangup(+3600 normal_clearing)
993. 2023-06-29 14:56:06.985708 25.73% [INFO] switch_rtp.c:7310 Auto Changing audio RTCP port from 172.16.0.2:4009 to 104.28.197.140:27235
994. EXECUTE [depth=0] sofia/lcr/8998591630@192.168.49.253:5060 sched_hangup(+3600 normal_clearing)
995. 2023-06-29 14:56:07.285737 25.73% [INFO] switch_rtp.c:7894 Auto Changing audio port from 192.168.49.253:11422 to 192.143.71.151:11422
996. 2023-06-29 14:56:07.485707 25.73% [NOTICE] sofia.c:1065 Hangup sofia/default/3140257213@Motor743.voice.upstreamconnect.co.za [CS_EXECUTE] [NORMAL_CLEARING]
997. 2023-06-29 14:56:07.485707 25.73% [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/27215318144 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
998. 2023-06-29 14:56:07.505699 25.73% [INFO] mod_json_cdr.c:271 Process [421f5656-f3b9-4bb7-94d3-4266ebbd323c.cdr.json]
999. 2023-06-29 14:56:07.505699 25.73% [INFO] mod_json_cdr.c:271 Process [e7cca772-44cd-4b8d-8863-0d173903b2b4.cdr.json]
1000. 2023-06-29 14:56:07.525702 25.73% [NOTICE] switch_core_session.c:1771 Session 29034 (sofia/default/27215318144) Ended
1001. 2023-06-29 14:56:07.525702 25.73% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/27215318144 [CS_DESTROY]
1002. 2023-06-29 14:56:07.645695 25.73% [NOTICE] switch_core_session.c:1771 Session 29032 (sofia/default/3140257213@Motor743.voice.upstreamconnect.co.za) Ended
1003. 2023-06-29 14:56:07.645695 25.73% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/3140257213@Motor743.voice.upstreamconnect.co.za [CS_DESTROY]
1004. 2023-06-29 14:56:08.025696 25.50% [NOTICE] sofia.c:1065 Hangup sofia/default/103@172.16.0.2:56406 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1005. EXECUTE [depth=0] sofia/default/27836592090@169.239.8.50 lua(astpp/lib/pbx/astpp-sipdevice-routing.lua)
1006. 2023-06-29 14:56:08.025696 25.50% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] This Is Last Dispositions SUCCESS This Is SIP Number : 103
1007. 2023-06-29 14:56:08.025696 25.50% [NOTICE] switch_core_state_machine.c:382 sofia/default/27836592090@169.239.8.50 has executed the last dialplan instruction, hanging up.
1008. 2023-06-29 14:56:08.025696 25.50% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/default/27836592090@169.239.8.50 [CS_EXECUTE] [NORMAL_CLEARING]
1009. 2023-06-29 14:56:08.025696 25.50% [INFO] mod_json_cdr.c:271 Process [d6db2976-7e8e-4c21-8949-800e383ff319.cdr.json]
1010. 2023-06-29 14:56:08.025696 25.50% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [update sip_subscriptions set version=version+1 where hostname='astpp' and profile_name='lcr' and sip_subscriptions.event != 'line-seize' and sip_subscriptions.proto='sip' and (event='presence' or event='dialog') and sub_to_user='103' and (sub_to_host='Trevor920.voice.upstreamconnect.co.za' or sub_to_host='154.0.2.205' or sub_to_host='154.0.2.205' or presence_hosts like '%Trevor920.voice.upstreamconnect.co.za%') and (sip_subscriptions.profile_name = 'lcr' or presence_hosts like '%Trevor920.voice.upstreamconnect.co.za%')]
1011. 2023-06-29 14:56:08.045709 25.50% [INFO] mod_json_cdr.c:271 Process [7cb7ccf9-ec2f-45c0-beef-4683214f847b.cdr.json]
1012. 2023-06-29 14:56:08.065699 25.50% [NOTICE] switch_core_session.c:1771 Session 29086 (sofia/default/103@172.16.0.2:56406) Ended
1013. 2023-06-29 14:56:08.065699 25.50% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/103@172.16.0.2:56406 [CS_DESTROY]
1014. 2023-06-29 14:56:08.085764 25.50% [NOTICE] switch_core_session.c:1771 Session 29084 (sofia/default/27836592090@169.239.8.50) Ended
1015. 2023-06-29 14:56:08.085764 25.50% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/27836592090@169.239.8.50 [CS_DESTROY]
1016. 2023-06-29 14:56:08.125699 25.50% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [update sip_subscriptions set version=version+1 where hostname='astpp' and profile_name='default' and sip_subscriptions.event != 'line-seize' and sip_subscriptions.proto='sip' and (event='presence' or event='dialog') and sub_to_user='27836592090' and (sub_to_host='169.239.8.50' or sub_to_host='154.0.2.205' or sub_to_host='154.0.2.205' or presence_hosts like '%169.239.8.50%') and (sip_subscriptions.profile_name = 'default' or presence_hosts like '%169.239.8.50%')]
1017. 2023-06-29 14:56:08.245726 25.50% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [2501252019@Woodbridge643.voice.upstreamconnect.co.za] from ip 41.147.0.180
1018. 2023-06-29 14:56:08.845688 25.20% [NOTICE] switch_channel.c:1123 New Channel sofia/lcr/27448016300@169.239.8.50 [ccabcb4c-29a3-48ad-82cf-a42ab3aecb4a]
1019. 2023-06-29 14:56:08.845688 25.20% [INFO] sofia.c:10462 sofia/lcr/27448016300@169.239.8.50 receiving invite from 169.239.8.50:5060 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 23069223_119522855@169.239.8.23~2o
1020. 2023-06-29 14:56:08.865710 25.20% [INFO] mod_dialplan_xml.c:639 Processing 27448016300 <27448016300>->27448745140 in context default
1021. 2023-06-29 14:56:08.865710 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [feature_code_call_transfer] Blind Call Flag: 0
1022. 2023-06-29 14:56:08.865710 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 27448745140
1023. 2023-06-29 14:56:08.865710 25.20% [WARNING] switch_cpp.cpp:1465 [ASTPP] [FAXER] Custom Caller ID
1024. 2023-06-29 14:56:08.865710 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 27448016300 / 27448016300
1025. 2023-06-29 14:56:08.865710 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
1026. 2023-06-29 14:56:08.865710 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : inbound
1027. 2023-06-29 14:56:08.865710 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9541167238]
1028. 2023-06-29 14:56:08.885917 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id : 1
1029. 2023-06-29 14:56:08.905717 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
1030. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 27448745140
1031. 2023-06-29 14:56:08.905717 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
1032. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : inbound
1033. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
1034. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 4
1035. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9541167238
1036. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 10000
1037. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
1038. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
1039. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
1040. 2023-06-29 14:56:08.905717 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
1041. 2023-06-29 14:56:08.905717 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DID Global Translation Id: 1
1042. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : inbound
1043. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
1044. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 68
1045. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.00000
1046. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
1047. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.00000
1048. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : 27448745140
1049. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 171
1050. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 4
1051. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
1052. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your10000 balance Accountid 4 !!!
1053. 2023-06-29 14:56:08.925833 25.20% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Call Is Free - Assigning Max Length  :: 60
1054. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 60 minutes
1055. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] INB_FREE:TRUE
1056. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] free_inbound:1
1057. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML:4
1058. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Userinfo XML:4
1059. 2023-06-29 14:56:08.925833 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [userinfo] Actual CustomerInfo XML : 4
1060. 2023-06-29 14:56:08.945699 25.20% [WARNING] switch_cpp.cpp:1465 [ASTPP] [user_domain] User Domain : DTM779.voice.upstreamconnect.co.za
1061. 2023-06-29 14:56:08.945699 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 60
1062. 2023-06-29 14:56:08.965706 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
1063. 2023-06-29 14:56:08.965706 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX] Call Direction : inbound
1064. 2023-06-29 14:56:08.986840 25.20% [WARNING] switch_cpp.cpp:1465 [ASTPP] [XML] User Domain : DTM779.voice.upstreamconnect.co.za
1065. 2023-06-29 14:56:09.025692 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP ID : 790
1066. 2023-06-29 14:56:09.045697 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] Call Forwarding Flag : 1
1067. 2023-06-29 14:56:09.045697 25.20% [INFO] switch_cpp.cpp:1465 [ASTPP] [PBX_SIP_ROUTING] SIP Call Forwarding Disable
1068. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(effective_destination_number=27448745140)
1069. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(bridge_pre_execute_bleg_app=sched_hangup)
1070. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(bridge_pre_execute_bleg_data=+3600 normal_clearing)
1071. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(callstart=2023-06-29 12:56:08)
1072. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(hangup_after_bridge=true)
1073. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(continue_on_fail=TRUE)
1074. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(account_id=4)
1075. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(parent_id=0)
1076. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(entity_id=3)
1077. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(call_processed=internal)
1078. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(call_direction=inbound)
1079. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(accountname=Switch)
1080. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(t38_passthru=true)
1081. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(fax_enable_t38=true)
1082. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(fax_enable_t38_request=true)
1083. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(filename1=astpp.pbx.lua)
1084. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(origination_rates_did=ID:68|CODE:^27448745140.*|DESTINATION:27448745140|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:0|COST:0.00000|INC:0|INITIALBLOCK:0|RATEGROUP:0|MARKUP:0|CI:171|ACCID:786)
1085. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(origination_rates=0)
1086. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(original_caller_id_name=27448016300)
1087. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(original_caller_id_number=27448016300)
1088. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(is_recording=1)
1089. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(media_bug_answer_req=true)
1090. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(RECORD_STEREO=true)
1091. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(record_sample_rate=8000)
1092. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/ccabcb4c-29a3-48ad-82cf-a42ab3aecb4aDID-LOCAL_786.wav)
1093. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(effective_caller_id_name=27448016300)
1094. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(effective_caller_id_number=27448016300)
1095. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(receiver_accid=786)
1096. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(domain_name=DTM779.voice.upstreamconnect.co.za)
1097. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(sip_to_host=DTM779.voice.upstreamconnect.co.za)
1098. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(presence_data=x|||DTM(3303416355)|||||||||DID|||169.239.8.50)
1099. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(call_type=0)
1100. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(calltype=DID-LOCAL)
1101. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 db(insert/pickup_any_group_/3303416355/ccabcb4c-29a3-48ad-82cf-a42ab3aecb4a)
1102. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(dialed_extension=transfer_call)
1103. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(dialed_extension=transfer_call)
1104. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
1105. 2023-06-29 14:56:09.165700 25.20% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
1106. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
1107. 2023-06-29 14:56:09.165700 25.20% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
1108. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(hangup_after_bridge=true)
1109. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(did_user_domain=DTM779.voice.upstreamconnect.co.za)
1110. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 db(insert/pickup_any_group_/3303416355/ccabcb4c-29a3-48ad-82cf-a42ab3aecb4a)
1111. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(dialed_extension=transfer_call)
1112. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 export(dialed_extension=transfer_call)
1113. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 bind_meta_app(3 b s execute_extension::transfer_call_3 XML default)
1114. 2023-06-29 14:56:09.185711 25.20% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *3 execute_extension::transfer_call_3 XML default
1115. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 bind_meta_app(2 b s execute_extension::transfer_call_2 XML default)
1116. 2023-06-29 14:56:09.185711 25.20% [INFO] switch_ivr_async.c:4774 Bound B-Leg: *2 execute_extension::transfer_call_2 XML default
1117. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(transfer_ringback=local_stream://moh)
1118. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(hangup_after_bridge=true)
1119. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 bridge({sip_invite_params=user=LOCAL,ignore_early_media=true,sip_h_P-call_type='custom_forward',sip_h_P-Accountcode=786}[leg_timeout=60 ] user/3303416355@DTM779.voice.upstreamconnect.co.za)
1120. 2023-06-29 14:56:09.225698 25.20% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [error] cause: [USER_NOT_REGISTERED]
1121. 2023-06-29 14:56:09.225698 25.20% [NOTICE] switch_ivr_originate.c:3039 Cannot create outgoing channel of type [user] cause: [USER_NOT_REGISTERED]
1122. 2023-06-29 14:56:09.225698 25.20% [INFO] mod_dptools.c:3653 Originate Failed.  Cause: USER_NOT_REGISTERED
1123. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(on_busy_flag=1)
1124. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(on_busy_destination=)
1125. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(no_answer_flag=1)
1126. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(no_answer_destination=)
1127. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(not_register_flag=1)
1128. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(not_register_destination=)
1129. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(opensips_flag=1)
1130. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(variable_sip_to_host=DTM779.voice.upstreamconnect.co.za)
1131. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(opensips_domain=7.0.0.)
1132. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(leg_timeout=60)
1133. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(userinfo_id=786)
1134. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(sip_destination_number=3303416355)
1135. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(did_number=3303416355)
1136. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(user_domain=DTM779.voice.upstreamconnect.co.za)
1137. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 lua(astpp/lib/pbx/astpp-sipdevice-routing.lua)
1138. 2023-06-29 14:56:09.225698 25.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] This Is Last Dispositions USER_NOT_REGISTERED This Is SIP Number : 3303416355
1139. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 answer()
1140. 2023-06-29 14:56:09.225698 25.20% [NOTICE] mod_dptools.c:1419 Channel [sofia/lcr/27448016300@169.239.8.50] has been answered
1141. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 record_session(/var/lib/freeswitch/recordings/ccabcb4c-29a3-48ad-82cf-a42ab3aecb4aDID-LOCAL_786.wav)
1142. 2023-06-29 14:56:09.245698 25.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] Dialed User For Voicemail :3303416355
1143. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 voicemail(default DTM779.voice.upstreamconnect.co.za 3303416355)
1144. 2023-06-29 14:56:09.245698 25.20% [WARNING] switch_core_db.c:92 SQLite is BUSY, sane=299 [update sip_subscriptions set version=version+1 where hostname='astpp' and profile_name='default' and sip_subscriptions.event != 'line-seize' and sip_subscriptions.proto='sip' and (event='presence' or event='dialog') and sub_to_user='27448016300' and (sub_to_host='169.239.8.50' or sub_to_host='154.0.2.205' or sub_to_host='154.0.2.205' or presence_hosts like '%169.239.8.50%') and (sip_subscriptions.profile_name = 'default' or presence_hosts like '%169.239.8.50%')]
1145. 2023-06-29 14:56:09.305751 25.20% [WARNING] mod_voicemail.c:3455 User [3303416355@DTM779.voice.upstreamconnect.co.za] have voicemail disabled
1146. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(on_busy_flag=1)
1147. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(on_busy_destination=)
1148. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(no_answer_flag=1)
1149. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(no_answer_destination=)
1150. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(not_register_flag=1)
1151. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(not_register_destination=)
1152. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(opensips_flag=1)
1153. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(variable_sip_to_host=DTM779.voice.upstreamconnect.co.za)
1154. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(opensips_domain=7.0.0.)
1155. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(leg_timeout=60)
1156. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(userinfo_id=786)
1157. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(sip_destination_number=3303416355)
1158. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(destination_number=27448745140)
1159. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(did_number=27448745140)
1160. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 set(user_domain=DTM779.voice.upstreamconnect.co.za)
1161. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 lua(astpp/lib/pbx/astpp-sipdevice-routing.lua)
1162. 2023-06-29 14:56:09.329226 25.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] This Is Last Dispositions USER_NOT_REGISTERED This Is SIP Number : 3303416355
1163. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 answer()
1164. 2023-06-29 14:56:09.329226 25.20% [NOTICE] switch_cpp.cpp:1465 [Astpp_SIP-Devices] Dialed User For Voicemail :3303416355
1165. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 voicemail(default DTM779.voice.upstreamconnect.co.za 3303416355)
1166. 2023-06-29 14:56:09.329226 25.20% [WARNING] mod_voicemail.c:3455 User [3303416355@DTM779.voice.upstreamconnect.co.za] have voicemail disabled
1167. EXECUTE [depth=0] sofia/lcr/27448016300@169.239.8.50 hangup(USER_NOT_REGISTERED)
1168. 2023-06-29 14:56:09.329226 25.20% [NOTICE] mod_dptools.c:1387 Hangup sofia/lcr/27448016300@169.239.8.50 [CS_EXECUTE] [USER_NOT_REGISTERED]
1169. 2023-06-29 14:56:09.365703 25.20% [INFO] mod_json_cdr.c:271 Process [ccabcb4c-29a3-48ad-82cf-a42ab3aecb4a.cdr.json]
1170. 2023-06-29 14:56:09.485691 25.20% [NOTICE] switch_core_session.c:1771 Session 29092 (sofia/lcr/27448016300@169.239.8.50) Ended
1171. 2023-06-29 14:56:09.485691 25.20% [NOTICE] switch_core_session.c:1775 Close Channel sofia/lcr/27448016300@169.239.8.50 [CS_DESTROY]
1172. 2023-06-29 14:56:09.625800 25.20% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [2754716633@Dr922.voice.upstreamconnect.co.za] from ip 102.182.12.146
1173. 2023-06-29 14:56:09.685703 24.73% [INFO] switch_channel.c:527 RECV DTMF 1:2880
1174. 2023-06-29 14:56:10.585700 24.73% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8998591630@Symbolic690.voice.upstreamconnect.co.za] from ip 192.143.71.151
1175. 2023-06-29 14:56:10.926343 24.30% [WARNING] sofia_reg.c:1861 SIP auth challenge (REGISTER) on sofia profile 'lcr' for [8987359711@Hydracor773.voice.upstreamconnect.co.za] from ip 192.168.94.253
1176. 2023-06-29 14:56:10.965701 24.30% [WARNING] switch_core_state_machine.c:684 ec7eaba3-021c-4cd8-b3c8-e0f806b75c1f sofia/lcr/100@1.1.1.1 Abandoned
1177. 2023-06-29 14:56:10.965701 24.30% [NOTICE] switch_core_state_machine.c:687 Hangup sofia/lcr/100@1.1.1.1 [CS_NEW] [WRONG_CALL_STATE]
1178. 2023-06-29 14:56:10.965701 24.30% [NOTICE] switch_core_session.c:1771 Session 29088 (sofia/lcr/100@1.1.1.1) Ended
1179.