1.  
2. 2023-07-17 14:26:12.725462 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/79998635436!
3. 2023-07-17 14:26:12.725462 [DEBUG] switch_channel.c:3565 (sofia/default/79998635436) Callstate Change DOWN -> EARLY
4. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
5. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
6. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
7. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
8. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
9. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
10. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
11. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/79998635436 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
12. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_codec.c:111 sofia/default/79998635436 Original read codec set to PCMA:8
13. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
14. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:5911 sofia/default/79998635436 Set 2833 dtmf send payload to 101 recv payload to 101
15. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/79998635436] 51.91.38.123 port 28238 -> 64.225.66.47 port 17268 codec: 8 ms: 20
16. 2023-07-17 14:26:12.725462 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
17. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8977 sofia/default/79998635436 Set 2833 dtmf send payload to 101
18. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8984 sofia/default/79998635436 Set 2833 dtmf receive payload to 101
19. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:9007 sofia/default/79998635436 Set rtp dtmf delay to 40
20. 2023-07-17 14:26:12.725462 [INFO] switch_ivr_originate.c:3801 Sending early media
21. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/0011600@78.46.81.253] 51.91.38.123 port 27100 -> 78.46.81.253 port 16678 codec: 8 ms: 20
22. 2023-07-17 14:26:12.725462 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
23. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8977 sofia/default/0011600@78.46.81.253 Set 2833 dtmf send payload to 101
24. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:8984 sofia/default/0011600@78.46.81.253 Set 2833 dtmf receive payload to 101
25. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_media.c:9007 sofia/default/0011600@78.46.81.253 Set rtp dtmf delay to 40
26. 2023-07-17 14:26:12.725462 [DEBUG] mod_sofia.c:2593 Ring SDP:
27. v=0
28. o=FreeSWITCH 1689566072 1689566073 IN IP4 51.91.38.123
29. s=FreeSWITCH
30. c=IN IP4 51.91.38.123
31. t=0 0
32. m=audio 27100 RTP/AVP 8 101
33. a=rtpmap:8 PCMA/8000
34. a=rtpmap:101 telephone-event/8000
35. a=fmtp:101 0-16
36. a=ptime:20
37. a=sendrecv
38.  
39. 2023-07-17 14:26:12.725462 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/0011600@78.46.81.253!
40. 2023-07-17 14:26:12.725462 [DEBUG] switch_channel.c:3565 (sofia/default/0011600@78.46.81.253) Callstate Change RINGING -> EARLY
41. 2023-07-17 14:26:12.725462 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/79998635436]
42. 2023-07-17 14:26:12.725462 [DEBUG] sofia.c:7326 Channel sofia/default/0011600@78.46.81.253 entering state [early][183]
43. 2023-07-17 14:26:12.725462 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/79998635436) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
44. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/79998635436) Running State Change CS_EXCHANGE_MEDIA (Cur 122 Tot 1217842)
45. 2023-07-17 14:26:12.725462 [DEBUG] switch_core_state_machine.c:654 (sofia/default/79998635436) State EXCHANGE_MEDIA
46. 2023-07-17 14:26:12.725462 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
47. 2023-07-17 14:26:12.785462 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
48. 2023-07-17 14:26:12.845462 [NOTICE] switch_channel.c:1118 New Channel sofia/default/4286316243@195.13.202.252 [682ba773-384f-4ba7-8e14-afbf9e43aefb]
49. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4286316243@195.13.202.252) Running State Change CS_NEW (Cur 123 Tot 1217843)
50. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:10280 sofia/default/4286316243@195.13.202.252 receiving invite from 51.91.38.125:5060 version: 1.10.5 -release-17-25569c1631 64bit
51. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:10374 verifying acl "default" for ip/port 51.91.38.125:0.
52. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:10403 IP 51.91.38.125 Approved by acl "default[]". Access Granted.
53. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:7326 Channel sofia/default/4286316243@195.13.202.252 entering state [received][100]
54. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:7336 Remote SDP:
55. v=0
56. o=root 972164589 972164589 IN IP4 195.13.202.252
57. s=Asterisk PBX 13.30.0
58. c=IN IP4 195.13.202.252
59. t=0 0
60. m=audio 17038 RTP/AVP 8 0 101
61. a=rtpmap:8 PCMA/8000
62. a=rtpmap:0 PCMU/8000
63. a=rtpmap:101 telephone-event/8000
64. a=fmtp:101 0-16
65. a=ptime:20
66. a=maxptime:150
67.  
68. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
69. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
70. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
71. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
72. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
73. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
74. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
75. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
76. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
77. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/4286316243@195.13.202.252 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
78. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_codec.c:111 sofia/default/4286316243@195.13.202.252 Original read codec set to PCMA:8
79. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
80. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_media.c:5911 sofia/default/4286316243@195.13.202.252 Set 2833 dtmf send payload to 101 recv payload to 101
81. 2023-07-17 14:26:12.845462 [DEBUG] sofia.c:7760 (sofia/default/4286316243@195.13.202.252) State Change CS_NEW -> CS_INIT
82. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:604 (sofia/default/4286316243@195.13.202.252) State NEW
83. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4286316243@195.13.202.252) Running State Change CS_INIT (Cur 123 Tot 1217843)
84. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4286316243@195.13.202.252) State INIT
85. 2023-07-17 14:26:12.845462 [DEBUG] mod_sofia.c:93 sofia/default/4286316243@195.13.202.252 SOFIA INIT
86. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:40 sofia/default/4286316243@195.13.202.252 Standard INIT
87. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:48 (sofia/default/4286316243@195.13.202.252) State Change CS_INIT -> CS_ROUTING
88. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:628 (sofia/default/4286316243@195.13.202.252) State INIT going to sleep
89. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4286316243@195.13.202.252) Running State Change CS_ROUTING (Cur 123 Tot 1217843)
90. 2023-07-17 14:26:12.845462 [DEBUG] switch_channel.c:2332 (sofia/default/4286316243@195.13.202.252) Callstate Change DOWN -> RINGING
91. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:644 (sofia/default/4286316243@195.13.202.252) State ROUTING
92. 2023-07-17 14:26:12.845462 [DEBUG] mod_sofia.c:154 sofia/default/4286316243@195.13.202.252 SOFIA ROUTING
93. 2023-07-17 14:26:12.845462 [DEBUG] switch_core_state_machine.c:236 sofia/default/4286316243@195.13.202.252 Standard ROUTING
94. 2023-07-17 14:26:12.845462 [INFO] mod_dialplan_xml.c:637 Processing 4286316243 <4286316243>->79052938350 in context default
95. 2023-07-17 14:26:12.845462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes')
96. 2023-07-17 14:26:12.845462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
97. 2023-07-17 14:26:12.845462 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Dialed number : 79052938350
98. 2023-07-17 14:26:12.845462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '79052938350' AND status=0 limit 1
99. 2023-07-17 14:26:12.845462 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Caller Id name / number  : 4286316243 / 4286316243
100. 2023-07-17 14:26:12.845462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="79052938350" LIMIT 1
101. 2023-07-17 14:26:12.845462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT accounts.is_recording,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="79052938350" limit 1
102. 2023-07-17 14:26:12.865461 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Call direction : outbound
103. 2023-07-17 14:26:12.865461 [INFO] switch_cpp.cpp:1447 [ASTPP] [Accountcode : 3403464963]
104. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE number = "3403464963" AND deleted = 0 limit 1
105. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^79052938350.*' OR blocked_patterns = '^7905293835.*' OR blocked_patterns = '^790529383.*' OR blocked_patterns = '^79052938.*' OR blocked_patterns = '^7905293.*' OR blocked_patterns = '^790529.*' OR blocked_patterns = '^79052.*' OR blocked_patterns = '^7905.*' OR blocked_patterns = '^790.*' OR blocked_patterns = '^79.*' OR blocked_patterns = '^7.*' OR blocked_patterns ='--') AND accountid = 330 limit 1
106. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="79052938350" AND A.accountid = '330' limit 1
107. 2023-07-17 14:26:12.865461 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 79052938350
108. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="79052938350" LIMIT 1
109. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT accounts.is_recording,sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="79052938350" limit 1
110. 2023-07-17 14:26:12.865461 [INFO] switch_cpp.cpp:1447 [ASTPP] [Dialplan] New Call direction : outbound
111. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="79052938350" AND (accountid = 0 OR status = 1) LIMIT 1
112. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE (patterns = '^79052938350.*' OR patterns = '^7905293835.*' OR patterns = '^790529383.*' OR patterns = '^79052938.*' OR patterns = '^7905293.*' OR patterns = '^790529.*' OR patterns = '^79052.*' OR patterns = '^7905.*' OR patterns = '^790.*' OR patterns = '^79.*' OR patterns = '^7.*' OR patterns ='--') AND accountid = 330 ORDER BY LENGTH(PKGPTR.patterns) DESC
113. 2023-07-17 14:26:12.865461 [DEBUG] sofia.c:7326 Channel sofia/default/79611261234 entering state [proceeding][183]
114. 2023-07-17 14:26:12.865461 [DEBUG] sofia.c:7336 Remote SDP:
115. v=0
116. o=root 279536636 279536636 IN IP4 64.225.66.47
117. s=Asterisk PBX 13.35.0
118. c=IN IP4 64.225.66.47
119. t=0 0
120. m=audio 12166 RTP/AVP 8 0 101
121. a=rtpmap:8 PCMA/8000
122. a=rtpmap:0 PCMU/8000
123. a=rtpmap:101 telephone-event/8000
124. a=fmtp:101 0-16
125. a=ptime:20
126. a=maxptime:150
127.  
128. 2023-07-17 14:26:12.865461 [NOTICE] sofia.c:7339 Pre-Answer sofia/default/79611261234!
129. 2023-07-17 14:26:12.865461 [DEBUG] switch_channel.c:3565 (sofia/default/79611261234) Callstate Change DOWN -> EARLY
130. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
131. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
132. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
133. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
134. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
135. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
136. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
137. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/79611261234 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
138. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_codec.c:111 sofia/default/79611261234 Original read codec set to PCMA:8
139. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
140. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:5911 sofia/default/79611261234 Set 2833 dtmf send payload to 101 recv payload to 101
141. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/79611261234] 51.91.38.123 port 21202 -> 64.225.66.47 port 12166 codec: 8 ms: 20
142. 2023-07-17 14:26:12.865461 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
143. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type FROM call_barring WHERE ((number = '79052938350' OR number = '7905293835' OR number = '790529383' OR number = '79052938' OR number = '7905293' OR number = '790529' OR number = '79052' OR number = '7905' OR number = '790' OR number = '79' OR number = '7' OR number ='--') OR (number = '79052938350*' OR number = '7905293835*' OR number = '790529383*' OR number = '79052938*' OR number = '7905293*' OR number = '790529*' OR number = '79052*' OR number = '7905*' OR number = '790*' OR number = '79*' OR number = '7*' OR number ='--') OR (number = '4286316243' OR number = '428631624' OR number = '42863162' OR number = '4286316' OR number = '428631' OR number = '42863' OR number = '4286' OR number = '428' OR number = '42' OR number = '4' OR number ='--') OR (number = '4286316243*' OR number = '428631624*' OR number = '42863162*' OR number = '4286316*' OR number = '428631*' OR number = '42863*' OR number = '4286*' OR number = '428*' OR number = '42*' OR number = '4*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC
144. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:8977 sofia/default/79611261234 Set 2833 dtmf send payload to 101
145. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:8984 sofia/default/79611261234 Set 2833 dtmf receive payload to 101
146. 2023-07-17 14:26:12.865461 [DEBUG] switch_core_media.c:9007 sofia/default/79611261234 Set rtp dtmf delay to 40
147. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] Call Barring Status : -1
148. 2023-07-17 14:26:12.865461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '79052938350' OR routing_prefix = '7905293835' OR routing_prefix = '790529383' OR routing_prefix = '79052938' OR routing_prefix = '7905293' OR routing_prefix = '790529' OR routing_prefix = '79052' OR routing_prefix = '7905' OR routing_prefix = '790' OR routing_prefix = '79' OR routing_prefix = '7' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1
149. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Account Information ===================
150. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] User id : 330
151. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Account code : 3403464963
152. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Balance : 1496.2236
153. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
154. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Ratecard id : 164
155. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] CID Pool : 0
156. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================================================
157. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 164 AND status = 0
158. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] call_direction :outbound
159. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 164 AND status = 0
160. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^79052938350.*' OR pattern = '^7905293835.*' OR pattern = '^790529383.*' OR pattern = '^79052938.*' OR pattern = '^7905293.*' OR pattern = '^790529.*' OR pattern = '^79052.*' OR pattern = '^7905.*' OR pattern = '^790.*' OR pattern = '^79.*' OR pattern = '^7.*' OR pattern ='--') AND status = 0 AND (pricelist_id = 164 OR accountid=330)  ORDER BY accountid DESC,LENGTH(pattern) DESC,cost DESC LIMIT 1
161. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] call_direction:::::: outbound
162. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Rates Information ===================
163. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 47254
164. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
165. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Includedseconds : 0
166. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Cost : 0.05900
167. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] comment : 3TrunksRF_GSM
168. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Country Id : 0
169. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Accid : 330
170. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk ID: 416,91,426,0,0
171. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Routing type:
172. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ================================================================
173. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Your1496.2236 balance Accountid 330 !!!
174. 2023-07-17 14:26:12.885462 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [FIND_MAXLENGTH] Limiting call to config max length 180 mins!
175. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Call Max length duration : 180 minutes
176. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] [DIALPLAN] User Rate ID : 47254
177. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_CARRIER_RATES]123131 Query rate_carrier_id  :416,91,426,0,0
178. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^79052938350.*' OR pattern = '^7905293835.*' OR pattern = '^790529383.*' OR pattern = '^79052938.*' OR pattern = '^7905293.*' OR pattern = '^790529.*' OR pattern = '^79052.*' OR pattern = '^7905.*' OR pattern = '^790.*' OR pattern = '^79.*' OR pattern = '^7.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (416,91,426,0,0)  ORDER by FIELD(TR.trunk_id,416,91,426,0,0),LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC
179. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Termination Rates Information ===================
180. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 5558
181. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Code : ^79.*
182. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Destination : YouT_79GSM
183. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
184. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Free Seconds : 0
185. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prefix : ^79.*
186. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Strip :
187. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prepend :
188. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Carrier id : 416
189. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] carrier_name : YouTel
190. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] dialplan_variable :
191. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Failover gateway :
192. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Vendor id : 512
193. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Max channels : 0
194. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk Name : YoutellGSM_79
195. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================END OF TERMINATION RATES=======================
196. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Termination Rates Information ===================
197. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 4885
198. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Code : ^79.*
199. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Destination : gsmrfh
200. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
201. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Free Seconds : 0
202. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prefix : ^79.*
203. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Strip :
204. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prepend :
205. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Carrier id : 91
206. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] carrier_name : husainovNEW
207. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] dialplan_variable :
208. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Failover gateway :
209. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Vendor id : 17
210. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Max channels : 0
211. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk Name : GSMhusainovRF
212. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================END OF TERMINATION RATES=======================
213. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] =============== Termination Rates Information ===================
214. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ID : 5572
215. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Code : ^79.*
216. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Destination : Proton_RF_79
217. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Connectcost : 0.00000
218. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Free Seconds : 0
219. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prefix : ^79.*
220. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Strip :
221. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Prepend :
222. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Carrier id : 426
223. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] carrier_name : ProtonCLI
224. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] dialplan_variable :
225. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Failover gateway :
226. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Vendor id : 736
227. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Max channels : 0
228. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] Trunk Name : ProtonRF_Random_79
229. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] ========================END OF TERMINATION RATES=======================
230. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_SIP_DEVICE_ID] Query :select id from sip_devices where username = '4286316243' and status =0
231. 2023-07-17 14:26:12.885462 [INFO] switch_ivr_originate.c:3801 Sending early media
232. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/0015000@78.46.81.253] 51.91.38.123 port 16528 -> 78.46.81.253 port 14330 codec: 8 ms: 20
233. 2023-07-17 14:26:12.885462 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
234. 2023-07-17 14:26:12.885462 [INFO] switch_cpp.cpp:1447 [ASTPP] SIP Device id : 7644
235. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 330 AND status=0 LIMIT 1
236. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_media.c:8977 sofia/default/0015000@78.46.81.253 Set 2833 dtmf send payload to 101
237. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_media.c:8984 sofia/default/0015000@78.46.81.253 Set 2833 dtmf receive payload to 101
238. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_media.c:9007 sofia/default/0015000@78.46.81.253 Set rtp dtmf delay to 40
239. 2023-07-17 14:26:12.885462 [DEBUG] mod_sofia.c:2593 Ring SDP:
240. v=0
241. o=FreeSWITCH 1689576644 1689576645 IN IP4 51.91.38.123
242. s=FreeSWITCH
243. c=IN IP4 51.91.38.123
244. t=0 0
245. m=audio 16528 RTP/AVP 8 101
246. a=rtpmap:8 PCMA/8000
247. a=rtpmap:101 telephone-event/8000
248. a=fmtp:101 0-16
249. a=ptime:20
250. a=sendrecv
251.  
252. 2023-07-17 14:26:12.885462 [NOTICE] mod_sofia.c:2596 Pre-Answer sofia/default/0015000@78.46.81.253!
253. 2023-07-17 14:26:12.885462 [DEBUG] switch_channel.c:3565 (sofia/default/0015000@78.46.81.253) Callstate Change RINGING -> EARLY
254. 2023-07-17 14:26:12.885462 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/79611261234]
255. 2023-07-17 14:26:12.885462 [DEBUG] sofia.c:7326 Channel sofia/default/0015000@78.46.81.253 entering state [early][183]
256. 2023-07-17 14:26:12.885462 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/79611261234) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
257. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/79611261234) Running State Change CS_EXCHANGE_MEDIA (Cur 123 Tot 1217843)
258. 2023-07-17 14:26:12.885462 [DEBUG] switch_core_state_machine.c:654 (sofia/default/79611261234) State EXCHANGE_MEDIA
259. 2023-07-17 14:26:12.885462 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
260. 2023-07-17 14:26:12.885462 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_OVERRIDE_CALLERID] Query :select am.id,am.number from ani_map as a,ani_map_numbers as am where a.id = am.ani_map_id and a.status ='0' and a.accountid = 330 and (prefix = '^79052938350.*' OR prefix = '^7905293835.*' OR prefix = '^790529383.*' OR prefix = '^79052938.*' OR prefix = '^7905293.*' OR prefix = '^790529.*' OR prefix = '^79052.*' OR prefix = '^7905.*' OR prefix = '^790.*' OR prefix = '^79.*' OR prefix = '^7.*' OR prefix ='--') and (((am.in_use=1 and a.sip_device_id=7644) or (am.in_use=0 and a.sip_device_id =7644)) or ((a.sip_device_id =-1 and am.in_use=1) or (a.sip_device_id = -1 and am.in_use =0))) order by a.prefix desc,a.sip_device_id desc,am.in_use desc limit 1
261. 2023-07-17 14:26:12.925461 [DEBUG] switch_ivr_bridge.c:655 sofia/default/79998635436 Bridge execute app sched_hangup(+10800 normal_clearing)
262. 2023-07-17 14:26:12.925461 [INFO] switch_cpp.cpp:1447 [ASTPP] maxlength::::::::: 180
263. 2023-07-17 14:26:12.925461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 164 AND status = 0
264. 2023-07-17 14:26:12.925461 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
265. 2023-07-17 14:26:12.925461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 416) AND status=0 limit 1
266. 2023-07-17 14:26:12.925461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"*/121#".....79052938350
267. span style="color: cornflowerblue; font-weight: bold;"> 2023-07-17 14:26:12.925461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 79052938350
268. 2023-07-17 14:26:12.925461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 121#79052938350
269. span style="color: green; font-weight: bold;"> 2023-07-17 14:26:12.925461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 164 AND status = 0
270. 2023-07-17 14:26:12.925461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 91) AND status=0 limit 1
271. 2023-07-17 14:26:12.945461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"*/726478#".....79052938350
272. span style="color: cornflowerblue; font-weight: bold;"> 2023-07-17 14:26:12.945461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 79052938350
273. 2023-07-17 14:26:12.945461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 726478#79052938350
274. span style="color: green; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 164 AND status = 0
275. 2023-07-17 14:26:12.945461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 426) AND status=0 limit 1
276. 2023-07-17 14:26:12.945461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [GET_LOCALIZATION] Query :"*/11597".....79052938350
277. 2023-07-17 14:26:12.945461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 79052938350
278. 2023-07-17 14:26:12.945461 [NOTICE] switch_cpp.cpp:1447 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 1159779052938350
279. 2023-07-17 14:26:12.945461 [DEBUG] switch_cpp.cpp:1447 [ASTPP] [Dialplan] Generated XML:
280. <?xml version="1.0" encoding="UTF-8" standalone="no"?>
281. <document type="freeswitch/xml">
282. <section name="dialplan" description="ASTPP Dialplan">
283. <context name="default">
284. <extension name="79052938350">
285. <condition field="destination_number" expression="79052938350">
286. <action application="set" data="effective_destination_number=79052938350"/>
287. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>
288. <action application="set" data="bridge_pre_execute_bleg_data=+10800 normal_clearing"/>
289. <action application="export" data="ani_map_id=444906"/>
290. <action application="set" data="callstart=2023-07-17 11:26:12"/>
291. <action application="set" data="hangup_after_bridge=true"/>
292. <action application="set" data="continue_on_fail=TRUE"/>
293. <action application="set" data="account_id=330"/>
294. <action application="set" data="parent_id=0"/>
295. <action application="set" data="entity_id=0"/>
296. <action application="set" data="call_processed=internal"/>
297. <action application="set" data="call_direction=outbound"/>
298. <action application="set" data="accountname=default"/>
299. <action application="export" data="t38_passthru=true"/>
300. <action application="set" data="fax_enable_t38=true"/>
301. <action application="set" data="fax_enable_t38_request=true"/>
302. <action application="set" data="origination_rates=ID:47254|CODE:^79.*|DESTINATION:3TrunksRF_GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05900|INC:1|INITIALBLOCK:1|RATEGROUP:164|MARKUP:0|CI:0|ACCID:330"/>
303. <action application="set" data="original_caller_id_name=79111111111"/>
304. <action application="set" data="original_caller_id_number=79111111111"/>
305.  
306. <action application="export" data="is_recording=1"/>
307. <action application="export" data="media_bug_answer_req=true"/>
308. <action application="export" data="record_sample_rate=8000"/>
309. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.mp3"/>
310. <action application="set" data="effective_caller_id_name=79111111111"/>
311. <action application="set" data="effective_caller_id_number=79111111111"/>
312. <action application="set" data="calltype=STANDARD"/>
313. <action application="set" data="termination_rates=ID:5558|CODE:^79.*|DESTINATION:YouT_79GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:416|PROVIDER:512"/>
314. <action application="set" data="trunk_id=416"/>
315. <action application="set" data="provider_id=512"/>
316. <action application="set" data="rate_flag=0"/>
317. <action application="set" data="force_trunk_flag=47254"/>
318. <action application="export" data="presence_data=trunk_id=416"/>
319. <action application="set" data="intcall=0"/>
320. <action application="export" data="presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125"/>
321. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/YouTel/121#79052938350;fs_path=sip:51.91.38.125:5060"/>
322. <action application="set" data="effective_caller_id_name=79111111111"/>
323. <action application="set" data="effective_caller_id_number=79111111111"/>
324. <action application="set" data="calltype=STANDARD"/>
325. <action application="set" data="termination_rates=ID:4885|CODE:^79.*|DESTINATION:gsmrfh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.05000|INC:1|INITIALBLOCK:1|TRUNK:91|PROVIDER:17"/>
326. <action application="set" data="trunk_id=91"/>
327. <action application="set" data="provider_id=17"/>
328. <action application="set" data="rate_flag=0"/>
329. <action application="set" data="force_trunk_flag=47254"/>
330. <action application="export" data="presence_data=trunk_id=91"/>
331. <action application="set" data="intcall=0"/>
332. <action application="export" data="presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||GSMhusainovRF // ^79.* // gsmrfh // 0.05000 // trunk_id=91|||STD|||51.91.38.125"/>
333. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/husainovNEW/726478#79052938350;fs_path=sip:51.91.38.125:5060"/>
334. <action application="set" data="effective_caller_id_name=79111111111"/>
335. <action application="set" data="effective_caller_id_number=79111111111"/>
336. <action application="set" data="calltype=STANDARD"/>
337. <action application="set" data="termination_rates=ID:5572|CODE:^79.*|DESTINATION:Proton_RF_79|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04500|INC:1|INITIALBLOCK:1|TRUNK:426|PROVIDER:736"/>
338. <action application="set" data="trunk_id=426"/>
339. <action application="set" data="provider_id=736"/>
340. <action application="set" data="rate_flag=0"/>
341. <action application="set" data="force_trunk_flag=47254"/>
342. <action application="export" data="presence_data=trunk_id=426"/>
343. <action application="set" data="intcall=0"/>
344. <action application="export" data="presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||ProtonRF_Random_79 // ^79.* // Proton_RF_79 // 0.04500 // trunk_id=426|||STD|||51.91.38.125"/>
345. <action application="bridge" data="[leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/ProtonCLI/1159779052938350;fs_path=sip:51.91.38.125:5060"/>
346. </condition>
347. </extension>
348. </context>
349. </section>
350. </document>
351. Dialplan: sofia/default/4286316243@195.13.202.252 parsing [default->79052938350] continue=false
352. Dialplan: sofia/default/4286316243@195.13.202.252 Regex (PASS) [79052938350] destination_number(79052938350) =~ /79052938350/ break=on-false
353. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_destination_number=79052938350)
354. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(bridge_pre_execute_bleg_app=sched_hangup)
355. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
356. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(ani_map_id=444906)
357. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(callstart=2023-07-17 11:26:12)
358. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(hangup_after_bridge=true)
359. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(continue_on_fail=TRUE)
360. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(account_id=330)
361. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(parent_id=0)
362. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(entity_id=0)
363. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(call_processed=internal)
364. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(call_direction=outbound)
365. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(accountname=default)
366. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(t38_passthru=true)
367. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(fax_enable_t38=true)
368. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(fax_enable_t38_request=true)
369. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(origination_rates=ID:47254|CODE:^79.*|DESTINATION:3TrunksRF_GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05900|INC:1|INITIALBLOCK:1|RATEGROUP:164|MARKUP:0|CI:0|ACCID:330)
370. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(original_caller_id_name=79111111111)
371. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(original_caller_id_number=79111111111)
372. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(is_recording=1)
373. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(media_bug_answer_req=true)
374. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(record_sample_rate=8000)
375. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.mp3)
376. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_name=79111111111)
377. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_number=79111111111)
378. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(calltype=STANDARD)
379. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(termination_rates=ID:5558|CODE:^79.*|DESTINATION:YouT_79GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:416|PROVIDER:512)
380. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(trunk_id=416)
381. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(provider_id=512)
382. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(rate_flag=0)
383. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(force_trunk_flag=47254)
384. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=trunk_id=416)
385. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(intcall=0)
386. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125)
387. Dialplan: sofia/default/4286316243@195.13.202.252 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/YouTel/121#79052938350;fs_path=sip:51.91.38.125:5060)
388. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_name=79111111111)
389. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_number=79111111111)
390. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(calltype=STANDARD)
391. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(termination_rates=ID:4885|CODE:^79.*|DESTINATION:gsmrfh|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.05000|INC:1|INITIALBLOCK:1|TRUNK:91|PROVIDER:17)
392. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(trunk_id=91)
393. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(provider_id=17)
394. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(rate_flag=0)
395. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(force_trunk_flag=47254)
396. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=trunk_id=91)
397. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(intcall=0)
398. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||GSMhusainovRF // ^79.* // gsmrfh // 0.05000 // trunk_id=91|||STD|||51.91.38.125)
399. Dialplan: sofia/default/4286316243@195.13.202.252 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/husainovNEW/726478#79052938350;fs_path=sip:51.91.38.125:5060)
400. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_name=79111111111)
401. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(effective_caller_id_number=79111111111)
402. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(calltype=STANDARD)
403. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(termination_rates=ID:5572|CODE:^79.*|DESTINATION:Proton_RF_79|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04500|INC:1|INITIALBLOCK:1|TRUNK:426|PROVIDER:736)
404. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(trunk_id=426)
405. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(provider_id=736)
406. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(rate_flag=0)
407. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(force_trunk_flag=47254)
408. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=trunk_id=426)
409. Dialplan: sofia/default/4286316243@195.13.202.252 Action set(intcall=0)
410. Dialplan: sofia/default/4286316243@195.13.202.252 Action export(presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||ProtonRF_Random_79 // ^79.* // Proton_RF_79 // 0.04500 // trunk_id=426|||STD|||51.91.38.125)
411. Dialplan: sofia/default/4286316243@195.13.202.252 Action bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/ProtonCLI/1159779052938350;fs_path=sip:51.91.38.125:5060)
412. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:287 (sofia/default/4286316243@195.13.202.252) State Change CS_ROUTING -> CS_EXECUTE
413. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:644 (sofia/default/4286316243@195.13.202.252) State ROUTING going to sleep
414. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:585 (sofia/default/4286316243@195.13.202.252) Running State Change CS_EXECUTE (Cur 123 Tot 1217843)
415. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:651 (sofia/default/4286316243@195.13.202.252) State EXECUTE
416. 2023-07-17 14:26:12.945461 [DEBUG] mod_sofia.c:209 sofia/default/4286316243@195.13.202.252 SOFIA EXECUTE
417. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:329 sofia/default/4286316243@195.13.202.252 Standard EXECUTE
418. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(effective_destination_number=79052938350)
419. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [effective_destination_number]=[79052938350]
420. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(bridge_pre_execute_bleg_app=sched_hangup)
421. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [bridge_pre_execute_bleg_app]=[sched_hangup]
422. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(bridge_pre_execute_bleg_data=+10800 normal_clearing)
423. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [bridge_pre_execute_bleg_data]=[+10800 normal_clearing]
424. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(ani_map_id=444906)
425. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [ani_map_id]=[444906]
426. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(callstart=2023-07-17 11:26:12)
427. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [callstart]=[2023-07-17 11:26:12]
428. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(hangup_after_bridge=true)
429. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [hangup_after_bridge]=[true]
430. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(continue_on_fail=TRUE)
431. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [continue_on_fail]=[TRUE]
432. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(account_id=330)
433. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [account_id]=[330]
434. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(parent_id=0)
435. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [parent_id]=[0]
436. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(entity_id=0)
437. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [entity_id]=[0]
438. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(call_processed=internal)
439. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [call_processed]=[internal]
440. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(call_direction=outbound)
441. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [call_direction]=[outbound]
442. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(accountname=default)
443. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [accountname]=[default]
444. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(t38_passthru=true)
445. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [t38_passthru]=[true]
446. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(fax_enable_t38=true)
447. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [fax_enable_t38]=[true]
448. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(fax_enable_t38_request=true)
449. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [fax_enable_t38_request]=[true]
450. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(origination_rates=ID:47254|CODE:^79.*|DESTINATION:3TrunksRF_GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05900|INC:1|INITIALBLOCK:1|RATEGROUP:164|MARKUP:0|CI:0|ACCID:330)
451. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [origination_rates]=[ID:47254|CODE:^79.*|DESTINATION:3TrunksRF_GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|CT:|COST:0.05900|INC:1|INITIALBLOCK:1|RATEGROUP:164|MARKUP:0|CI:0|ACCID:330]
452. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(original_caller_id_name=79111111111)
453. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [original_caller_id_name]=[79111111111]
454. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(original_caller_id_number=79111111111)
455. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [original_caller_id_number]=[79111111111]
456. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(is_recording=1)
457. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [is_recording]=[1]
458. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(media_bug_answer_req=true)
459. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [media_bug_answer_req]=[true]
460. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(record_sample_rate=8000)
461. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [record_sample_rate]=[8000]
462. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(execute_on_answer=record_session /var/lib/freeswitch/recordings/682ba773-384f-4ba7-8e14-afbf9e43aefb.mp3)
463. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/682ba773-384f-4ba7-8e14-afbf9e43aefb.mp3]
464. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(effective_caller_id_name=79111111111)
465. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [effective_caller_id_name]=[79111111111]
466. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(effective_caller_id_number=79111111111)
467. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [effective_caller_id_number]=[79111111111]
468. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(calltype=STANDARD)
469. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [calltype]=[STANDARD]
470. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(termination_rates=ID:5558|CODE:^79.*|DESTINATION:YouT_79GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:416|PROVIDER:512)
471. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [termination_rates]=[ID:5558|CODE:^79.*|DESTINATION:YouT_79GSM|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:416|PROVIDER:512]
472. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(trunk_id=416)
473. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [trunk_id]=[416]
474. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(provider_id=512)
475. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [provider_id]=[512]
476. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(rate_flag=0)
477. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [rate_flag]=[0]
478. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(force_trunk_flag=47254)
479. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [force_trunk_flag]=[47254]
480. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(presence_data=trunk_id=416)
481. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=416]
482. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 set(intcall=0)
483. 2023-07-17 14:26:12.945461 [DEBUG] mod_dptools.c:1672 SET sofia/default/4286316243@195.13.202.252 [intcall]=[0]
484. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 export(presence_data=x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125)
485. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125]
486. EXECUTE [depth=0] sofia/default/4286316243@195.13.202.252 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/YouTel/121#79052938350;fs_path=sip:51.91.38.125:5060)
487. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [ani_map_id]=[444906] to event
488. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [t38_passthru]=[true] to event
489. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [is_recording]=[1] to event
490. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
491. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
492. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/682ba773-384f-4ba7-8e14-afbf9e43aefb.mp3] to event
493. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [presence_data]=[x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125] to event
494. 2023-07-17 14:26:12.945461 [DEBUG] switch_channel.c:1264 sofia/default/4286316243@195.13.202.252 EXPORTING[export_vars] [presence_data]=[x|||TADM(3403464963)|||^79.* // 3TrunksRF_GSM // 0.05900 // trunk_id=416,91,426,0,0|||YoutellGSM_79 // ^79.* // YouT_79GSM // 0.04700 // trunk_id=416|||STD|||51.91.38.125] to event
495. 2023-07-17 14:26:12.945461 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
496. 2023-07-17 14:26:12.945461 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables
497. 2023-07-17 14:26:12.945461 [NOTICE] switch_channel.c:1118 New Channel sofia/default/121#79052938350 [ff4ec033-de3a-4b29-900e-c5cda6a2258e]
498. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] mod_sofia.c:5089 (sofia/default/121#79052938350) State Change CS_NEW -> CS_INIT
499. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79052938350) Running State Change CS_INIT (Cur 124 Tot 1217844)
500. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:628 (sofia/default/121#79052938350) State INIT
501. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] mod_sofia.c:93 sofia/default/121#79052938350 SOFIA INIT
502. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_ivr.c:632 sofia/default/79998635436 Command Execute [depth=0] sched_hangup(+10800 normal_clearing)
503. EXECUTE [depth=0] sofia/default/79998635436 sched_hangup(+10800 normal_clearing)
504. 2023-07-17 14:26:12.945461 [DEBUG] switch_scheduler.c:252 Added task 482121 switch_ivr_schedule_hangup (b46876bf-ad05-4c4d-9d06-69b26dac5bc6) to run at 1689603972
505. 2023-07-17 14:26:12.945461 [DEBUG] sofia_glue.c:1587 sip:51.91.38.125 Setting proxy route to sofia/default/121#79052938350
506. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] sofia_glue.c:1618 sofia/default/121#79052938350 sending invite version: 1.10.5 -release-17-25569c1631 64bit
507. Local SDP:
508. v=0
509. o=FreeSWITCH 1689573546 1689573547 IN IP4 51.91.38.123
510. s=FreeSWITCH
511. c=IN IP4 51.91.38.123
512. t=0 0
513. m=audio 19626 RTP/AVP 8 0 101
514. a=rtpmap:8 PCMA/8000
515. a=rtpmap:0 PCMU/8000
516. a=rtpmap:101 telephone-event/8000
517. a=fmtp:101 0-16
518. a=ptime:20
519. a=sendrecv
520.  
521. 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:40 sofia/default/121#79052938350 Standard INIT
522. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:48 (sofia/default/121#79052938350) State Change CS_INIT -> CS_ROUTING
523. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:628 (sofia/default/121#79052938350) State INIT going to sleep
524. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79052938350) Running State Change CS_ROUTING (Cur 124 Tot 1217844)
525. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] sofia.c:7326 Channel sofia/default/121#79052938350 entering state [calling][0]
526. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:644 (sofia/default/121#79052938350) State ROUTING
527. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] mod_sofia.c:154 sofia/default/121#79052938350 SOFIA ROUTING
528. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_ivr_originate.c:67 (sofia/default/121#79052938350) State Change CS_ROUTING -> CS_CONSUME_MEDIA
529. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:644 (sofia/default/121#79052938350) State ROUTING going to sleep
530. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79052938350) Running State Change CS_CONSUME_MEDIA (Cur 124 Tot 1217844)
531. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:663 (sofia/default/121#79052938350) State CONSUME_MEDIA
532. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:12.945461 [DEBUG] switch_core_state_machine.c:663 (sofia/default/121#79052938350) State CONSUME_MEDIA going to sleep
533. span style="color: green; font-weight: bold;"> 2023-07-17 14:26:12.965461 [INFO] mod_shout.c:332 LAME 3.100 64bits (http://lame.sf.net)
534. 2023-07-17 14:26:12.965461 [INFO] mod_shout.c:332 polyphase lowpass filter disabled
535. 2023-07-17 14:26:13.065491 [DEBUG] switch_ivr_bridge.c:655 sofia/default/79611261234 Bridge execute app sched_hangup(+10800 normal_clearing)
536. 2023-07-17 14:26:13.085463 [DEBUG] switch_ivr.c:632 sofia/default/79611261234 Command Execute [depth=0] sched_hangup(+10800 normal_clearing)
537. EXECUTE [depth=0] sofia/default/79611261234 sched_hangup(+10800 normal_clearing)
538. 2023-07-17 14:26:13.085463 [DEBUG] switch_scheduler.c:252 Added task 482122 switch_ivr_schedule_hangup (273851cb-374b-4bbf-b3c0-178ce6f87f43) to run at 1689603973
539. 2023-07-17 14:26:13.225491 [DEBUG] sofia.c:7326 Channel sofia/default/8844479528431996 entering state [proceeding][183]
540. 2023-07-17 14:26:13.225491 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/8844479528431996!
541. 2023-07-17 14:26:13.225491 [DEBUG] switch_channel.c:3437 (sofia/default/8844479528431996) Callstate Change DOWN -> RINGING
542. 2023-07-17 14:26:13.225491 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/79651097840@65.108.39.19!
543. 2023-07-17 14:26:13.225491 [DEBUG] sofia.c:7326 Channel sofia/default/8844479528431996 entering state [terminated][486]
544. 2023-07-17 14:26:13.225491 [NOTICE] sofia.c:8559 Hangup sofia/default/8844479528431996 [CS_CONSUME_MEDIA] [USER_BUSY]
545. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8844479528431996) Running State Change CS_HANGUP (Cur 124 Tot 1217844)
546. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:848 (sofia/default/8844479528431996) Callstate Change RINGING -> HANGUP
547. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8844479528431996) State HANGUP
548. 2023-07-17 14:26:13.225491 [DEBUG] mod_sofia.c:453 Channel sofia/default/8844479528431996 hanging up, cause: USER_BUSY
549. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:60 sofia/default/8844479528431996 Standard HANGUP, cause: USER_BUSY
550. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:850 (sofia/default/8844479528431996) State HANGUP going to sleep
551. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:620 (sofia/default/8844479528431996) State Change CS_HANGUP -> CS_REPORTING
552. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:585 (sofia/default/8844479528431996) Running State Change CS_REPORTING (Cur 124 Tot 1217844)
553. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8844479528431996) State REPORTING
554. 2023-07-17 14:26:13.225491 [INFO] mod_json_cdr.c:271 Process [b9d0d558-c4f5-4ac7-8148-e6eb44b5366c.cdr.json]
555. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:174 sofia/default/8844479528431996 Standard REPORTING, cause: USER_BUSY
556. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:936 (sofia/default/8844479528431996) State REPORTING going to sleep
557. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_state_machine.c:611 (sofia/default/8844479528431996) State Change CS_REPORTING -> CS_DESTROY
558. 2023-07-17 14:26:13.225491 [DEBUG] switch_core_session.c:1726 Session 1217840 (sofia/default/8844479528431996) Locked, Waiting on external entities
559. 2023-07-17 14:26:13.245475 [DEBUG] switch_ivr_originate.c:3995 Originate Resulted in Error Cause: 17 [USER_BUSY]
560. 2023-07-17 14:26:13.245475 [NOTICE] switch_core_session.c:1744 Session 1217840 (sofia/default/8844479528431996) Ended
561. 2023-07-17 14:26:13.245475 [NOTICE] switch_core_session.c:1748 Close Channel sofia/default/8844479528431996 [CS_DESTROY]
562. 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:739 (sofia/default/8844479528431996) Running State Change CS_DESTROY (Cur 123 Tot 1217844)
563. 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8844479528431996) State DESTROY
564. 2023-07-17 14:26:13.245475 [DEBUG] mod_sofia.c:364 sofia/default/8844479528431996 SOFIA DESTROY
565. 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:181 sofia/default/8844479528431996 Standard DESTROY
566. 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:749 (sofia/default/8844479528431996) State DESTROY going to sleep
567. 2023-07-17 14:26:13.245475 [INFO] mod_dptools.c:3631 Originate Failed.  Cause: USER_BUSY
568. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(effective_caller_id_name=79651097840)
569. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [effective_caller_id_name]=[79651097840]
570. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(effective_caller_id_number=79651097840)
571. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [effective_caller_id_number]=[79651097840]
572. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(calltype=STANDARD)
573. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [calltype]=[STANDARD]
574. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(termination_rates=ID:5556|CODE:^3379.*|DESTINATION:YouTellRfGSM_3379|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:414|PROVIDER:512)
575. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [termination_rates]=[ID:5556|CODE:^3379.*|DESTINATION:YouTellRfGSM_3379|CONNECTIONCOST:0.00000|INCLUDEDSECONDS:0|COST:0.04700|INC:1|INITIALBLOCK:1|TRUNK:414|PROVIDER:512]
576. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(trunk_id=414)
577. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [trunk_id]=[414]
578. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(provider_id=512)
579. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [provider_id]=[512]
580. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(rate_flag=0)
581. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [rate_flag]=[0]
582. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(force_trunk_flag=47958)
583. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [force_trunk_flag]=[47958]
584. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 export(presence_data=trunk_id=414)
585. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[trunk_id=414]
586. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 set(intcall=0)
587. 2023-07-17 14:26:13.245475 [DEBUG] mod_dptools.c:1672 SET sofia/default/79651097840@65.108.39.19 [intcall]=[0]
588. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 export(presence_data=x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125)
589. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125]
590. EXECUTE [depth=0] sofia/default/79651097840@65.108.39.19 bridge([leg_timeout=0,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/YouTel/121#79528431996;fs_path=sip:51.91.38.125:5060)
591. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [t38_passthru]=[true] to event
592. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [is_recording]=[1] to event
593. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
594. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [record_sample_rate]=[8000] to event
595. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [execute_on_answer]=[record_session /var/lib/freeswitch/recordings/6cdad9ff-c5e4-4e0f-a780-2fa52f56a703.mp3] to event
596. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
597. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
598. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
599. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
600. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
601. 2023-07-17 14:26:13.245475 [DEBUG] switch_channel.c:1264 sofia/default/79651097840@65.108.39.19 EXPORTING[export_vars] [presence_data]=[x|||johny ebanosi(4823033694)|||^3379.* // 3TrunksRF_GSM // 0.05900 // trunk_id=428,454,414,0,0|||YouTellRfGSM_3379 // ^3379.* // YouTellRfGSM_3379 // 0.04700 // trunk_id=414|||STD|||51.91.38.125] to event
602. 2023-07-17 14:26:13.245475 [DEBUG] switch_ivr_originate.c:2242 Parsing global variables
603. 2023-07-17 14:26:13.245475 [DEBUG] switch_ivr_originate.c:2804 Parsing session specific variables
604. 2023-07-17 14:26:13.245475 [NOTICE] switch_channel.c:1118 New Channel sofia/default/121#79528431996 [571108bb-8de2-4b55-afbc-d0243687a638]
605. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] mod_sofia.c:5089 (sofia/default/121#79528431996) State Change CS_NEW -> CS_INIT
606. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79528431996) Running State Change CS_INIT (Cur 124 Tot 1217845)
607. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:628 (sofia/default/121#79528431996) State INIT
608. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] mod_sofia.c:93 sofia/default/121#79528431996 SOFIA INIT
609. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] sofia_glue.c:1587 sip:51.91.38.125 Setting proxy route to sofia/default/121#79528431996
610. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] sofia_glue.c:1618 sofia/default/121#79528431996 sending invite version: 1.10.5 -release-17-25569c1631 64bit
611. Local SDP:
612. v=0
613. o=FreeSWITCH 1689574273 1689574274 IN IP4 51.91.38.123
614. s=FreeSWITCH
615. c=IN IP4 51.91.38.123
616. t=0 0
617. m=audio 18900 RTP/AVP 8 0 101
618. a=rtpmap:8 PCMA/8000
619. a=rtpmap:0 PCMU/8000
620. a=rtpmap:101 telephone-event/8000
621. a=fmtp:101 0-16
622. a=ptime:20
623. a=sendrecv
624.  
625. 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:40 sofia/default/121#79528431996 Standard INIT
626. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:48 (sofia/default/121#79528431996) State Change CS_INIT -> CS_ROUTING
627. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:628 (sofia/default/121#79528431996) State INIT going to sleep
628. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79528431996) Running State Change CS_ROUTING (Cur 124 Tot 1217845)
629. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] sofia.c:7326 Channel sofia/default/121#79528431996 entering state [calling][0]
630. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:644 (sofia/default/121#79528431996) State ROUTING
631. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] mod_sofia.c:154 sofia/default/121#79528431996 SOFIA ROUTING
632. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_ivr_originate.c:67 (sofia/default/121#79528431996) State Change CS_ROUTING -> CS_CONSUME_MEDIA
633. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:644 (sofia/default/121#79528431996) State ROUTING going to sleep
634. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:585 (sofia/default/121#79528431996) Running State Change CS_CONSUME_MEDIA (Cur 124 Tot 1217845)
635. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:663 (sofia/default/121#79528431996) State CONSUME_MEDIA
636. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.245475 [DEBUG] switch_core_state_machine.c:663 (sofia/default/121#79528431996) State CONSUME_MEDIA going to sleep
637. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.365486 [DEBUG] sofia.c:7326 Channel sofia/default/0279922248094 entering state [completing][200]
638. 2023-07-17 14:26:13.365486 [DEBUG] sofia.c:7336 Remote SDP:
639. v=0
640. o=- 876660 876660 IN IP4 192.0.2.1
641. s=Asterisk PBX 13.10.0
642. c=IN IP4 138.201.240.101
643. t=0 0
644. m=audio 28084 RTP/AVP 8 0 101
645. a=rtpmap:8 PCMA/8000
646. a=rtpmap:0 PCMU/8000
647. a=rtpmap:101 telephone-event/8000
648. a=fmtp:101 0-16
649. a=ptime:20
650. a=maxptime:150
651.  
652. 2023-07-17 14:26:13.365486 [DEBUG] sofia.c:7326 Channel sofia/default/0279922248094 entering state [ready][200]
653. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
654. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
655. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
656. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
657. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
658. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
659. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
660. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/0279922248094 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
661. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_codec.c:111 sofia/default/0279922248094 Original read codec set to PCMA:8
662. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
663. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:5911 sofia/default/0279922248094 Set 2833 dtmf send payload to 101 recv payload to 101
664. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/0279922248094] 51.91.38.123 port 19796 -> 138.201.240.101 port 28084 codec: 8 ms: 20
665. 2023-07-17 14:26:13.365486 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
666. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:8977 sofia/default/0279922248094 Set 2833 dtmf send payload to 101
667. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:8984 sofia/default/0279922248094 Set 2833 dtmf receive payload to 101
668. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media.c:9007 sofia/default/0279922248094 Set rtp dtmf delay to 40
669. 2023-07-17 14:26:13.365486 [NOTICE] sofia.c:8504 Channel [sofia/default/0279922248094] has been answered
670. EXECUTE [depth=1] sofia/default/0279922248094 record_session(/var/lib/freeswitch/recordings/5ba9e3ba-f614-4098-80cc-f680b38b9c7a.mp3)
671. 2023-07-17 14:26:13.365486 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 8000 -> 8000
672. 2023-07-17 14:26:13.365486 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/default/0279922248094
673. 2023-07-17 14:26:13.385463 [DEBUG] switch_channel.c:3865 (sofia/default/0279922248094) Callstate Change DOWN -> ACTIVE
674. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/6944058402@78.46.81.253] 51.91.38.123 port 26758 -> 78.46.81.253 port 10852 codec: 8 ms: 20
675. 2023-07-17 14:26:13.385463 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
676. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_media.c:8977 sofia/default/6944058402@78.46.81.253 Set 2833 dtmf send payload to 101
677. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_media.c:8984 sofia/default/6944058402@78.46.81.253 Set 2833 dtmf receive payload to 101
678. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_media.c:9007 sofia/default/6944058402@78.46.81.253 Set rtp dtmf delay to 40
679. 2023-07-17 14:26:13.385463 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/6944058402@78.46.81.253:
680. v=0
681. o=FreeSWITCH 1689566415 1689566416 IN IP4 51.91.38.123
682. s=FreeSWITCH
683. c=IN IP4 51.91.38.123
684. t=0 0
685. m=audio 26758 RTP/AVP 8 101
686. a=rtpmap:8 PCMA/8000
687. a=rtpmap:101 telephone-event/8000
688. a=fmtp:101 0-16
689. a=ptime:20
690. a=sendrecv
691.  
692. 2023-07-17 14:26:13.385463 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/default/6944058402@78.46.81.253] has been answered
693. 2023-07-17 14:26:13.385463 [DEBUG] sofia.c:7326 Channel sofia/default/6944058402@78.46.81.253 entering state [completed][200]
694. EXECUTE [depth=0] sofia/default/6944058402@78.46.81.253 record_session(/var/lib/freeswitch/recordings/5ba9e3ba-f614-4098-80cc-f680b38b9c7a.mp3)
695. 2023-07-17 14:26:13.385463 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 8000 -> 8000
696. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/default/6944058402@78.46.81.253
697. 2023-07-17 14:26:13.385463 [DEBUG] sofia.c:7326 Channel sofia/default/6944058402@78.46.81.253 entering state [ready][200]
698. 2023-07-17 14:26:13.385463 [DEBUG] switch_channel.c:3865 (sofia/default/6944058402@78.46.81.253) Callstate Change RINGING -> ACTIVE
699. 2023-07-17 14:26:13.385463 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/0279922248094]
700. 2023-07-17 14:26:13.385463 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/0279922248094) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
701. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_state_machine.c:585 (sofia/default/0279922248094) Running State Change CS_EXCHANGE_MEDIA (Cur 124 Tot 1217845)
702. 2023-07-17 14:26:13.385463 [DEBUG] switch_core_state_machine.c:654 (sofia/default/0279922248094) State EXCHANGE_MEDIA
703. 2023-07-17 14:26:13.385463 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
704. 2023-07-17 14:26:13.405462 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech
705. 2023-07-17 14:26:13.405462 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech
706. 2023-07-17 14:26:13.425462 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
707. 2023-07-17 14:26:13.425462 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
708. 2023-07-17 14:26:13.425462 [INFO] mod_shout.c:332 LAME 3.100 64bits (http://lame.sf.net)
709. 2023-07-17 14:26:13.425462 [INFO] mod_shout.c:332 polyphase lowpass filter disabled
710. 2023-07-17 14:26:13.485462 [DEBUG] sofia.c:7326 Channel sofia/default/79892381828 entering state [proceeding][183]
711. 2023-07-17 14:26:13.485462 [DEBUG] sofia.c:7336 Remote SDP:
712. v=0
713. o=- 918635 918636 IN IP4 192.0.2.1
714. s=Asterisk PBX 13.10.0
715. c=IN IP4 138.201.240.101
716. t=0 0
717. m=audio 14050 RTP/AVP 8 0 101
718. a=rtpmap:8 PCMA/8000
719. a=rtpmap:0 PCMU/8000
720. a=rtpmap:101 telephone-event/8000
721. a=fmtp:101 0-16
722. a=sendonly
723. a=ptime:20
724. a=maxptime:150
725.  
726. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
727. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
728. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
729. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
730. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
731. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
732. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
733. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
734. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:5911 sofia/default/79892381828 Set 2833 dtmf send payload to 101 recv payload to 101
735. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:8651 Audio params changed for sofia/default/79892381828 from 138.201.240.101:12644 to 138.201.240.101:14050
736. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/79892381828] 51.91.38.123 port 18556 -> 138.201.240.101 port 14050 codec: 8 ms: 20
737. 2023-07-17 14:26:13.485462 [DEBUG] switch_core_media.c:8692 AUDIO RTP CHANGING DEST TO: [138.201.240.101:14050]
738. 2023-07-17 14:26:13.525487 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
739. 2023-07-17 14:26:13.565486 [DEBUG] switch_ivr_bridge.c:655 sofia/default/0279922248094 Bridge execute app sched_hangup(+10800 normal_clearing)
740. 2023-07-17 14:26:13.585462 [DEBUG] switch_ivr.c:632 sofia/default/0279922248094 Command Execute [depth=0] sched_hangup(+10800 normal_clearing)
741. EXECUTE [depth=0] sofia/default/0279922248094 sched_hangup(+10800 normal_clearing)
742. 2023-07-17 14:26:13.585462 [DEBUG] switch_scheduler.c:252 Added task 482123 switch_ivr_schedule_hangup (68ce1e54-b5e6-47ab-8ffd-bb9f724a596b) to run at 1689603973
743. 2023-07-17 14:26:13.605462 [DEBUG] sofia.c:7326 Channel sofia/default/121#79052938350 entering state [proceeding][180]
744. span style="color: cornflowerblue; font-weight: bold;"> 2023-07-17 14:26:13.605462 [NOTICE] sofia.c:7437 Ring-Ready sofia/default/121#79052938350!
745. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.605462 [DEBUG] switch_channel.c:3437 (sofia/default/121#79052938350) Callstate Change DOWN -> RINGING
746. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.625462 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
747. 2023-07-17 14:26:13.625462 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
748. 2023-07-17 14:26:13.625462 [NOTICE] mod_sofia.c:2498 Ring-Ready sofia/default/4286316243@195.13.202.252!
749. 2023-07-17 14:26:13.625462 [DEBUG] sofia.c:7326 Channel sofia/default/4286316243@195.13.202.252 entering state [early][180]
750. 2023-07-17 14:26:13.625462 [NOTICE] switch_ivr_originate.c:556 Ring Ready sofia/default/4286316243@195.13.202.252!
751. 2023-07-17 14:26:13.665486 [WARNING] switch_core_media.c:3194 [CBR]: Asynchronous PTIME not supported, changing our end from 20 to 10
752. 2023-07-17 14:26:13.685463 [DEBUG] switch_core_media.c:3737 Changing Codec from PCMA@20ms@8000hz to PCMA@10ms@8000hz
753. 2023-07-17 14:26:13.705462 [DEBUG] sofia.c:7326 Channel sofia/default/0279506149415 entering state [completing][200]
754. 2023-07-17 14:26:13.705462 [DEBUG] sofia.c:7336 Remote SDP:
755. v=0
756. o=- 859845 859845 IN IP4 192.0.2.1
757. s=Asterisk PBX 13.10.0
758. c=IN IP4 138.201.240.101
759. t=0 0
760. m=audio 58170 RTP/AVP 8 0 101
761. a=rtpmap:8 PCMA/8000
762. a=rtpmap:0 PCMU/8000
763. a=rtpmap:101 telephone-event/8000
764. a=fmtp:101 0-16
765. a=ptime:20
766. a=maxptime:150
767.  
768. 2023-07-17 14:26:13.705462 [DEBUG] sofia.c:7326 Channel sofia/default/0279506149415 entering state [ready][200]
769. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
770. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
771. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
772. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
773. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
774. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
775. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
776. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/0279506149415 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
777. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_codec.c:111 sofia/default/0279506149415 Original read codec set to PCMA:8
778. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
779. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:5911 sofia/default/0279506149415 Set 2833 dtmf send payload to 101 recv payload to 101
780. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/0279506149415] 51.91.38.123 port 32498 -> 138.201.240.101 port 58170 codec: 8 ms: 20
781. 2023-07-17 14:26:13.705462 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
782. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8977 sofia/default/0279506149415 Set 2833 dtmf send payload to 101
783. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8984 sofia/default/0279506149415 Set 2833 dtmf receive payload to 101
784. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:9007 sofia/default/0279506149415 Set rtp dtmf delay to 40
785. 2023-07-17 14:26:13.705462 [NOTICE] sofia.c:8504 Channel [sofia/default/0279506149415] has been answered
786. EXECUTE [depth=1] sofia/default/0279506149415 record_session(/var/lib/freeswitch/recordings/67daf048-35ba-4991-b29a-c0afa6d587b5.mp3)
787. 2023-07-17 14:26:13.705462 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 8000 -> 8000
788. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/default/0279506149415
789. 2023-07-17 14:26:13.705462 [DEBUG] switch_channel.c:3865 (sofia/default/0279506149415) Callstate Change DOWN -> ACTIVE
790. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8663 AUDIO RTP [sofia/default/6944058402@78.46.81.253] 51.91.38.123 port 24268 -> 78.46.81.253 port 16138 codec: 8 ms: 20
791. 2023-07-17 14:26:13.705462 [DEBUG] switch_rtp.c:4450 Starting timer [soft] 160 bytes per 20ms
792. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8977 sofia/default/6944058402@78.46.81.253 Set 2833 dtmf send payload to 101
793. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:8984 sofia/default/6944058402@78.46.81.253 Set 2833 dtmf receive payload to 101
794. 2023-07-17 14:26:13.705462 [DEBUG] switch_core_media.c:9007 sofia/default/6944058402@78.46.81.253 Set rtp dtmf delay to 40
795. 2023-07-17 14:26:13.705462 [DEBUG] mod_sofia.c:898 Local SDP sofia/default/6944058402@78.46.81.253:
796. v=0
797. o=FreeSWITCH 1689568905 1689568906 IN IP4 51.91.38.123
798. s=FreeSWITCH
799. c=IN IP4 51.91.38.123
800. t=0 0
801. m=audio 24268 RTP/AVP 8 101
802. a=rtpmap:8 PCMA/8000
803. a=rtpmap:101 telephone-event/8000
804. a=fmtp:101 0-16
805. a=ptime:20
806. a=sendrecv
807.  
808. 2023-07-17 14:26:13.705462 [NOTICE] switch_ivr_originate.c:3794 Channel [sofia/default/6944058402@78.46.81.253] has been answered
809. 2023-07-17 14:26:13.705462 [DEBUG] sofia.c:7326 Channel sofia/default/6944058402@78.46.81.253 entering state [completed][200]
810. EXECUTE [depth=0] sofia/default/6944058402@78.46.81.253 record_session(/var/lib/freeswitch/recordings/67daf048-35ba-4991-b29a-c0afa6d587b5.mp3)
811. 2023-07-17 14:26:13.725462 [DEBUG] switch_rtp.c:4325 RE-Starting timer [soft] 80 bytes per 10ms
812. 2023-07-17 14:26:13.725462 [DEBUG] switch_core_media.c:3839 Set Codec sofia/default/79892381828 PCMA/8000 10 ms 80 samples 64000 bits 1 channels
813. 2023-07-17 14:26:13.725462 [DEBUG] switch_core_codec.c:123 sofia/default/79892381828 Original read codec replaced with PCMA:8
814. 2023-07-17 14:26:13.725462 [DEBUG] switch_ivr_async.c:1351 Record session sample rate: 8000 -> 8000
815. 2023-07-17 14:26:13.725462 [DEBUG] switch_core_media_bug.c:970 Attaching BUG to sofia/default/6944058402@78.46.81.253
816. 2023-07-17 14:26:13.725462 [DEBUG] sofia.c:7326 Channel sofia/default/6944058402@78.46.81.253 entering state [ready][200]
817. 2023-07-17 14:26:13.725462 [DEBUG] switch_channel.c:3865 (sofia/default/6944058402@78.46.81.253) Callstate Change RINGING -> ACTIVE
818. 2023-07-17 14:26:13.725462 [DEBUG] switch_ivr_originate.c:3852 Originate Resulted in Success: [sofia/default/0279506149415]
819. 2023-07-17 14:26:13.725462 [DEBUG] switch_ivr_bridge.c:1793 (sofia/default/0279506149415) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
820. 2023-07-17 14:26:13.725462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/0279506149415) Running State Change CS_EXCHANGE_MEDIA (Cur 124 Tot 1217845)
821. 2023-07-17 14:26:13.725462 [DEBUG] switch_core_state_machine.c:654 (sofia/default/0279506149415) State EXCHANGE_MEDIA
822. 2023-07-17 14:26:13.725462 [DEBUG] mod_sofia.c:656 SOFIA EXCHANGE_MEDIA
823. 2023-07-17 14:26:13.725462 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech
824. 2023-07-17 14:26:13.725462 [DEBUG] switch_ivr_async.c:1640 No silence detection configured; assuming start of speech
825. 2023-07-17 14:26:13.745486 [DEBUG] switch_core_media.c:16090 Engaging Write Buffer at 320 bytes to accommodate 160->320
826. 2023-07-17 14:26:13.765462 [DEBUG] switch_rtp.c:7759 Correct audio ip/port confirmed.
827. 2023-07-17 14:26:13.765462 [DEBUG] switch_core_io.c:448 Setting BUG Codec PCMA:8
828. 2023-07-17 14:26:13.905462 [NOTICE] sofia.c:1089 Hangup sofia/default/0014000@78.46.81.253 [CS_EXECUTE] [NORMAL_CLEARING]
829. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/0014000@78.46.81.253]
830. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:655 sofia/default/0279506149415 Bridge execute app sched_hangup(+10800 normal_clearing)
831. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:820 sofia/default/0014000@78.46.81.253 ending bridge by request from write function
832. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:912 BRIDGE THREAD DONE [sofia/default/726478#79875814103]
833. span style="color: cornflowerblue; font-weight: bold;"> 2023-07-17 14:26:13.905462 [NOTICE] switch_ivr_bridge.c:1029 Hangup sofia/default/726478#79875814103 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
834. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:654 (sofia/default/726478#79875814103) State EXCHANGE_MEDIA going to sleep
835. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/726478#79875814103) Running State Change CS_HANGUP (Cur 124 Tot 1217845)
836. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_async.c:1445 Stop recording file /var/lib/freeswitch/recordings/5bd00758-dbf8-4137-9787-ee0f80bb55b1.mp3
837. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:1892 sofia/default/726478#79875814103 skip receive message [UNBRIDGE] (channel is hungup already)
838. span style="color: goldenrod; font-weight: bold;"> 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_bridge.c:1895 sofia/default/0014000@78.46.81.253 skip receive message [UNBRIDGE] (channel is hungup already)
839. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_session.c:2905 sofia/default/0014000@78.46.81.253 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
840. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:651 (sofia/default/0014000@78.46.81.253) State EXECUTE going to sleep
841. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/0014000@78.46.81.253) Running State Change CS_HANGUP (Cur 124 Tot 1217845)
842. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_async.c:1445 Stop recording file /var/lib/freeswitch/recordings/5bd00758-dbf8-4137-9787-ee0f80bb55b1.mp3
843. 2023-07-17 14:26:13.905462 [DEBUG] switch_ivr_async.c:1520 Channel is hung up
844. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_media_bug.c:1295 Removing BUG from sofia/default/0014000@78.46.81.253
845. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:848 (sofia/default/0014000@78.46.81.253) Callstate Change ACTIVE -> HANGUP
846. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:850 (sofia/default/0014000@78.46.81.253) State HANGUP
847. 2023-07-17 14:26:13.905462 [DEBUG] mod_sofia.c:453 Channel sofia/default/0014000@78.46.81.253 hanging up, cause: NORMAL_CLEARING
848. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:60 sofia/default/0014000@78.46.81.253 Standard HANGUP, cause: NORMAL_CLEARING
849. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:850 (sofia/default/0014000@78.46.81.253) State HANGUP going to sleep
850. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:620 (sofia/default/0014000@78.46.81.253) State Change CS_HANGUP -> CS_REPORTING
851. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:585 (sofia/default/0014000@78.46.81.253) Running State Change CS_REPORTING (Cur 124 Tot 1217845)
852. 2023-07-17 14:26:13.905462 [DEBUG] switch_core_state_machine.c:936 (sofia/default/0014000@78.46.81.253) State REPORTING
853. 2023-07-17 14:26:13.905462 [INFO] mod_json_cdr.c:271 Process [5bd00758-dbf8-4137-9787-ee0f80bb55b1.cdr.json]
854.