1. 2024-04-17 14:00:32.497507 94.50% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
2. 2024-04-17 14:00:32.497507 94.50% [DEBUG] switch_core_io.c:447 Setting BUG Codec PCMA:8
3. 2024-04-17 14:00:33.557506 94.67% [NOTICE] switch_channel.c:1142 New Channel sofia/default/sky111v6@tdsl.skytel.rs [c62300b1-02ef-4df8-b418-f3bb95236bbb]
4. 2024-04-17 14:00:33.557506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_NEW (Cur 29 Tot 248044)
5. 2024-04-17 14:00:33.557506 94.67% [INFO] sofia.c:10459 sofia/default/sky111v6@tdsl.skytel.rs receiving invite from 103.240.35.46:48124 version: 1.10.10-release  64bit call-id: YmNmMmRjOTE5NWVlOGJiYjI3YmFhYjU0MTAyYzA4M2I.
6. 2024-04-17 14:00:33.557506 94.67% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 103.240.35.46:0.
7. 2024-04-17 14:00:33.557506 94.67% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [00381653552255@tdsl.skytel.rs] from ip 103.240.35.46
8. 2024-04-17 14:00:33.557506 94.67% [DEBUG] switch_core_state_machine.c:600 (sofia/default/sky111v6@tdsl.skytel.rs) State NEW
9. 2024-04-17 14:00:33.557506 94.67% [DEBUG] sofia.c:2419 detaching session c62300b1-02ef-4df8-b418-f3bb95236bbb
10. 2024-04-17 14:00:33.937504 94.67% [DEBUG] sofia.c:2532 Re-attaching to session c62300b1-02ef-4df8-b418-f3bb95236bbb
11. 2024-04-17 14:00:33.957512 94.67% [INFO] sofia.c:10459 sofia/default/sky111v6@tdsl.skytel.rs receiving invite from 103.240.35.46:48124 version: 1.10.10-release  64bit call-id: YmNmMmRjOTE5NWVlOGJiYjI3YmFhYjU0MTAyYzA4M2I.
12. 2024-04-17 14:00:33.957512 94.67% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 103.240.35.46:0.
13. 2024-04-17 14:00:33.977506 94.67% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
14. 2024-04-17 14:00:33.977506 94.67% [DEBUG] sofia.c:7493 Channel sofia/default/sky111v6@tdsl.skytel.rs entering state [received][100]
15. 2024-04-17 14:00:33.977506 94.67% [DEBUG] sofia.c:7503 Remote SDP:
16. v=0
17. o=Z 0 0 IN IP4 103.240.35.46
18. s=Z
19. c=IN IP4 103.240.35.46
20. t=0 0
21. m=audio 8000 RTP/AVP 3 110 8 0 98 101
22. a=rtpmap:110 speex/8000
23. a=rtpmap:98 iLBC/8000
24. a=fmtp:98 mode=20
25. a=rtpmap:101 telephone-event/8000
26. a=fmtp:101 0-15
27.  
28. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
29. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
30. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
31. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
32. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
33. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
34. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
35. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
36. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
37. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
38. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
39. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
40. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
41. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/sky111v6@tdsl.skytel.rs PCMA/8000 20 ms 160 samples 64000 bits 1 channels
42. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_codec.c:111 sofia/default/sky111v6@tdsl.skytel.rs Original read codec set to PCMA:8
43. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
44. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_media.c:5856 sofia/default/sky111v6@tdsl.skytel.rs Set 2833 dtmf send payload to 101 recv payload to 101
45. 2024-04-17 14:00:33.977506 94.67% [DEBUG] sofia.c:7927 (sofia/default/sky111v6@tdsl.skytel.rs) State Change CS_NEW -> CS_INIT
46. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_INIT (Cur 29 Tot 248044)
47. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/sky111v6@tdsl.skytel.rs) State INIT
48. 2024-04-17 14:00:33.977506 94.67% [DEBUG] mod_sofia.c:97 sofia/default/sky111v6@tdsl.skytel.rs SOFIA INIT
49. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/sky111v6@tdsl.skytel.rs Standard INIT
50. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/sky111v6@tdsl.skytel.rs) State Change CS_INIT -> CS_ROUTING
51. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/sky111v6@tdsl.skytel.rs) State INIT going to sleep
52. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_ROUTING (Cur 29 Tot 248044)
53. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_channel.c:2399 (sofia/default/sky111v6@tdsl.skytel.rs) Callstate Change DOWN -> RINGING
54. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/sky111v6@tdsl.skytel.rs) State ROUTING
55. 2024-04-17 14:00:33.977506 94.67% [DEBUG] mod_sofia.c:158 sofia/default/sky111v6@tdsl.skytel.rs SOFIA ROUTING
56. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_core_state_machine.c:230 sofia/default/sky111v6@tdsl.skytel.rs Standard ROUTING
57. 2024-04-17 14:00:33.977506 94.67% [INFO] mod_dialplan_xml.c:639 Processing sky111v6 <sky111v6>->00381653552255 in context default
58. 2024-04-17 14:00:33.977506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
59. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
60. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')                                                                                                                                                                                                
61. 2024-04-17 14:00:33.977506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
62. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 00381653552255
63. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '00381653552255' AND status=0 limit 1
64. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERsky111v6
65. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
66. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '00381653552255' limit 1
67. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : sky111v6 / sky111v6
68. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="00381653552255" LIMIT 1                                  
69. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
70. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="00381653552255" limit 1                                                                                                                                                                
71. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
72. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 3333456913]
73. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "3333456913" OR id = "3333456913") AND deleted = 0 limit 1
74. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^00381653552255.*' OR blocked_patterns = '^0038165355225.*' OR blocked_patterns = '^003816535522.*' OR blocked_patterns = '^00381653552.*' OR blocked_patterns = '^0038165355.*' OR blocked_patterns = '^003816535.*' OR blocked_patterns = '^00381653.*' OR blocked_patterns = '^0038165.*' OR blocked_patterns = '^003816.*' OR blocked_patterns = '^00381.*' OR blocked_patterns = '^0038.*' OR blocked_patterns = '^003.*' OR blocked_patterns = '^00.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 16 limit 1                                                                                                                                                                
75. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="00381653552255" AND (accountid = 0 OR status = 1) LIMIT 1
76. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 1
77. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 1 AND status=0 limit 1                                                                                                                                                                                            
78. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....00381653552255
79. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 00381653552255
80. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 381653552255
81. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....381653552255
82. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....381653552255
83. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
84. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
85. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6DBCLI00381653552255
86. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
87. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
88. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6DBCLI00381653552255
89. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
90. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
91. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6DBCLI00381653552255
92. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
93. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
94. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6DBCLI00381653552255
95. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
96. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
97. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6DBCLI00381653552255
98. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"387/38733922284/*/*,386/38618284888/*/*,385/38518880792/*/*,382/+38220690268/*/*,381/381114419555/*/*,3816/381114419888/*/*".....sky111v6DBCLI00381653552255                                                                                                                                                                        
99. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : sky111v6DBCLI00381653552255
100. 2024-04-17 14:00:33.997506 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : sky111v6
101. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^381653552255.*' OR patterns = '^38165355225.*' OR patterns = '^3816535522.*' OR patterns = '^381653552.*' OR patterns = '^38165355.*' OR patterns = '^3816535.*' OR patterns = '^381653.*' OR patterns = '^38165.*' OR patterns = '^3816.*' OR patterns = '^381.*' OR patterns = '^38.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 16 ORDER BY LENGTH(PKGPTR.patterns) DESC                                                                                                                                                                              
102. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="381653552255" AND A.accountid = '16' limit 1                                                                                                                                                                
103. 2024-04-17 14:00:33.997506 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 381653552255
104. 2024-04-17 14:00:33.997506 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="381653552255" LIMIT 1                                    
105. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
106. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="381653552255" limit 1                                                                                                                                                                  
107. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
108. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '381653552255' OR number = '38165355225' OR number = '3816535522' OR number = '381653552' OR number = '38165355' OR number = '3816535' OR number = '381653' OR number = '38165' OR number = '3816' OR number = '381' OR number = '38' OR number = '3' OR number ='--') OR (number = '381653552255*' OR number = '38165355225*' OR number = '3816535522*' OR number = '381653552*' OR number = '38165355*' OR number = '3816535*' OR number = '381653*' OR number = '38165*' OR number = '3816*' OR number = '381*' OR number = '38*' OR number = '3*' OR number ='--') OR (number = 'sky111v6' OR number = 'sky111v' OR number = 'sky111' OR number = 'sky11' OR number = 'sky1' OR number = 'sky' OR number = 'sk' OR number = 's' OR number ='--') OR (number = 'sky111v6*' OR number = 'sky111v*' OR number = 'sky111*' OR number = 'sky11*' OR number = 'sky1*' OR number = 'sky*' OR number = 'sk*' OR number = 's*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC                                                                  
109. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
110. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '381653552255' OR routing_prefix = '38165355225' OR routing_prefix = '3816535522' OR routing_prefix = '381653552' OR routing_prefix = '38165355' OR routing_prefix = '3816535' OR routing_prefix = '381653' OR routing_prefix = '38165' OR routing_prefix = '3816' OR routing_prefix = '381' OR routing_prefix = '38' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1                                          
111. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
112. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 16
113. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 3333456913
114. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 989.505
115. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
116. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 2
117. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
118. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
119. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 222222222222222222222222222222222222222222222222222222222222
120. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 111111111111111111111111111111111111111111111111      "*/*/*/*"
121. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 33333333333333333333333333333333333333333333333333333   "*/*/*/*"
122. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/*/*/*".....sky111v6
123. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : sky111v6
124. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : ky111v6
125. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/*/*/*".....sky111v6
126. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : sky111v6
127. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : ky111v6
128. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/*/*/*".....sky111v6
129. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : sky111v6
130. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : ky111v6
131. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"*/*/*/*".....sky111v6
132. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : sky111v6
133. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : ky111v6
134. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
135. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
136. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
137. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^381653552255.*' OR pattern = '^38165355225.*' OR pattern = '^3816535522.*' OR pattern = '^381653552.*' OR pattern = '^38165355.*' OR pattern = '^3816535.*' OR pattern = '^381653.*' OR pattern = '^38165.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND (caller_id_pattern = '^ky111v6.*' OR caller_id_pattern = '^ky111v.*' OR caller_id_pattern = '^ky111.*' OR caller_id_pattern = '^ky11.*' OR caller_id_pattern = '^ky1.*' OR caller_id_pattern = '^ky.*' OR caller_id_pattern = '^k.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 2 OR accountid=16)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1                                                                                                                                                    
138. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
139. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
140. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 678
141. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
142. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
143. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.1200000000
144. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Srbija-Mobilni
145. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 164
146. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 16
147. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
148. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
149. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
150. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your989.505 balance Accountid 16 !!!
151. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
152. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
153. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
154. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=2 ORDER by id asc
155. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^381653552255.*' OR pattern = '^38165355225.*' OR pattern = '^3816535522.*' OR pattern = '^381653552.*' OR pattern = '^38165355.*' OR pattern = '^3816535.*' OR pattern = '^381653.*' OR pattern = '^38165.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4,6,7,8,9,10,11,12,15,16,17,19,21,22) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC                    
156. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [HYBRID_ROUTING] LCR With Cost In 2nd Stage
157. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
158. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15926
159. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^3816.*
160. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Serbia-Mobilni
161. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
162. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
163. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^3816.*
164. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
165. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
166. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 4
167. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Asterisk
168. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
169. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
170. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 8
171. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
172. 2024-04-17 14:00:34.017514 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
173. 2024-04-17 14:00:34.017514 94.67% [NOTICE] switch_cpp.cpp:1465 [ASTPP] DIDWW_INT: 0.3158000000 > 0.1200000000, skipping for loss less routing
174. 2024-04-17 14:00:34.017514 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 16 AND status=0 LIMIT 1                                                                                                                                                                                        
175. 2024-04-17 14:00:34.037510 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
176. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = 'sky111v6' and status = 0 limit 1
177. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
178. 2024-04-17 14:00:34.037510 94.67% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 0
179. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 2 AND status = 0
180. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 8) AND status=0 limit 1                                                                                                                                                                  
181. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1                                                                                                                                                                    
182. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="00381653552255" LIMIT 1                                  
183. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
184. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
185. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
186. <document type="freeswitch/xml">                                                                                                                                                                                  
187. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
188. <context name="default">                                                                                                                                                                                          
189. <extension name="381653552255">                                                                                                                                                                                    
190. <condition field="destination_number" expression="00381653552255">                                                                                                                                                
191. <action application="set" data="effective_destination_number=381653552255"/>                                                                                                                                      
192. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>                                                                                                                                        
193. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>                                                                                                                              
194. <action application="set" data="type=0"/>                                                                                                                                                                          
195. <action application="set" data="callstart=2024-04-17 12:00:34"/>                                                                                                                                                  
196. <action application="set" data="hangup_after_bridge=true"/>                                                                                                                                                        
197. <action application="set" data="continue_on_fail=TRUE"/>                                                                                                                                                          
198. <action application="set" data="account_id=16"/>                                                                                                                                                                  
199. <action application="set" data="parent_id=0"/>                                                                                                                                                                    
200. <action application="set" data="entity_id=0"/>                                                                                                                                                                    
201. <action application="set" data="call_processed=internal"/>                                                                                                                                                        
202. <action application="set" data="call_direction=outbound"/>                                                                                                                                                        
203. <action application="set" data="accountname=default"/>                                                                                                                                                            
204. <action application="export" data="t38_passthru=true"/>                                                                                                                                                            
205. <action application="set" data="fax_enable_t38=true"/>                                                                                                                                                            
206. <action application="set" data="fax_enable_t38_request=true"/>                                                                                                                                                    
207. <action application="set" data="origination_rates=ID:678|CODE:^38165.*|DESTINATION:Srbija-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.1200000000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:164|ACCID:16"/>                                                                                                                                                                                                  
208. <action application="set" data="original_caller_id_name=SkyTel"/>                                                                                                                                                  
209. <action application="set" data="original_caller_id_number=sky111v6"/>                                                                                                                                              
210.  
211. <action application="export" data="is_recording=1"/>                                                                                                                                                              
212. <action application="export" data="media_bug_answer_req=true"/>                                                                                                                                                    
213. <action application="export" data="record_sample_rate=8000"/>                                                                                                                                                      
214. <action application="export" data="execute_on_answer=record_session $${recordings_dir}/${uuid}.wav"/>                                                                                                              
215. <action application="set" data="effective_caller_id_name=SkyTel"/>                                                                                                                                                
216. <action application="set" data="effective_caller_id_number=sky111v6"/>                                                                                                                                            
217. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
218. <action application="set" data="termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8"/>    
219. <action application="set" data="trunk_id=4"/>                                                                                                                                                                      
220. <action application="set" data="provider_id=8"/>                                                                                                                                                                  
221. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
222. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
223. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
224. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
225. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
226. <action application="set" data="quality_trunk_seq=4"/>                                                                                                                                                            
227. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
228. <action application="export" data="presence_data=trunk_id=4"/>                                                                                                                                                    
229. <action application="set" data="intcall=0"/>                                                                                                                                                                      
230. <action application="export" data="presence_id=381653552255"/>                                                                                                                                                    
231. <action application="export" data="presence_data=x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46"/>                                                                                                                                                                                                                
232. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381653552255"/>                                                                    
233. </condition>                                                                                                                                                                                                      
234. </extension>                                                                                                                                                                                                      
235. </context>                                                                                                                                                                                                        
236. </section>                                                                                                                                                                                                        
237. </document>                                                                                                                                                                                                        
238. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs parsing [default->381653552255] continue=false
239. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Regex (PASS) [381653552255] destination_number(00381653552255) =~ /00381653552255/ break=on-false
240. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(effective_destination_number=381653552255)
241. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(bridge_pre_execute_bleg_app=sched_hangup)
242. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
243. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(type=0)
244. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(callstart=2024-04-17 12:00:34)
245. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(hangup_after_bridge=true)
246. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(continue_on_fail=TRUE)
247. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(account_id=16)
248. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(parent_id=0)
249. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(entity_id=0)
250. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(call_processed=internal)
251. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(call_direction=outbound)
252. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(accountname=default)
253. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(t38_passthru=true)
254. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(fax_enable_t38=true)
255. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(fax_enable_t38_request=true)
256. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(origination_rates=ID:678|CODE:^38165.*|DESTINATION:Srbija-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.1200000000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:164|ACCID:16)                                                                                                                                                                        
257. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(original_caller_id_name=SkyTel)
258. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(original_caller_id_number=sky111v6)
259. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(is_recording=1)
260. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(media_bug_answer_req=true)
261. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(record_sample_rate=8000)
262. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(execute_on_answer=record_session $${recordings_dir}/${uuid}.wav)
263. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(effective_caller_id_name=SkyTel)
264. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(effective_caller_id_number=sky111v6)
265. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(calltype=STANDARD)
266. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                              
267. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(trunk_id=4)
268. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(provider_id=8)
269. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(rate_flag=0)
270. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(daily_call_credit_limit=0.00000)
271. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(monthly_call_credit_limit=0.00000)
272. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(daily_call_used_limit=0.00000)
273. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(monthly_call_used_limit=0.00000)
274. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(quality_trunk_seq=4)
275. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(force_trunk_flag=0)
276. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(presence_data=trunk_id=4)
277. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action set(intcall=0)
278. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(presence_id=381653552255)
279. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action export(presence_data=x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46)                                                                                                                                                                                      
280. Dialplan: sofia/default/sky111v6@tdsl.skytel.rs Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381653552255)
281. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_core_state_machine.c:281 (sofia/default/sky111v6@tdsl.skytel.rs) State Change CS_ROUTING -> CS_EXECUTE
282. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/sky111v6@tdsl.skytel.rs) State ROUTING going to sleep
283. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_EXECUTE (Cur 29 Tot 248044)
284. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_core_state_machine.c:647 (sofia/default/sky111v6@tdsl.skytel.rs) State EXECUTE
285. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_sofia.c:213 sofia/default/sky111v6@tdsl.skytel.rs SOFIA EXECUTE
286. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_core_state_machine.c:323 sofia/default/sky111v6@tdsl.skytel.rs Standard EXECUTE
287. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(effective_destination_number=381653552255)
288. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [effective_destination_number]=[381653552255]
289. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(bridge_pre_execute_bleg_app=sched_hangup)
290. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [bridge_pre_execute_bleg_app]=[sched_hangup]
291. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
292. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
293. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(type=0)
294. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [type]=[0]
295. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(callstart=2024-04-17 12:00:34)
296. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [callstart]=[2024-04-17 12:00:34]
297. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(hangup_after_bridge=true)
298. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [hangup_after_bridge]=[true]
299. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(continue_on_fail=TRUE)
300. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [continue_on_fail]=[TRUE]
301. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(account_id=16)
302. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [account_id]=[16]
303. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(parent_id=0)
304. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [parent_id]=[0]
305. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(entity_id=0)
306. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [entity_id]=[0]
307. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(call_processed=internal)
308. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [call_processed]=[internal]
309. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(call_direction=outbound)
310. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [call_direction]=[outbound]
311. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(accountname=default)
312. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [accountname]=[default]
313. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(t38_passthru=true)
314. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [t38_passthru]=[true]
315. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(fax_enable_t38=true)
316. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [fax_enable_t38]=[true]
317. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(fax_enable_t38_request=true)
318. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [fax_enable_t38_request]=[true]
319. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(origination_rates=ID:678|CODE:^38165.*|DESTINATION:Srbija-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.1200000000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:164|ACCID:16)                                                                                                                                                                        
320. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [origination_rates]=[ID:678|CODE:^38165.*|DESTINATION:Srbija-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.1200000000|INC:60|INITIALBLOCK:60|RATEGROUP:2|MARKUP:0|CI:164|ACCID:16]                                                                                                                          
321. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(original_caller_id_name=SkyTel)
322. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [original_caller_id_name]=[SkyTel]
323. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(original_caller_id_number=sky111v6)
324. 2024-04-17 14:00:34.037510 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [original_caller_id_number]=[sky111v6]
325. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(is_recording=1)
326. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [is_recording]=[1]
327. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(media_bug_answer_req=true)
328. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [media_bug_answer_req]=[true]
329. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(record_sample_rate=8000)
330. 2024-04-17 14:00:34.037510 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [record_sample_rate]=[8000]
331. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(execute_on_answer=record_session /usr/local/freeswitch/recordings/c62300b1-02ef-4df8-b418-f3bb95236bbb.wav)
332. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/c62300b1-02ef-4df8-b418-f3bb95236bbb.wav]
333. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(effective_caller_id_name=SkyTel)
334. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [effective_caller_id_name]=[SkyTel]
335. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(effective_caller_id_number=sky111v6)
336. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [effective_caller_id_number]=[sky111v6]
337. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(calltype=STANDARD)
338. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [calltype]=[STANDARD]
339. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                              
340. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [termination_rates]=[ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8]                                                                                                                                                
341. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(trunk_id=4)
342. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [trunk_id]=[4]
343. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(provider_id=8)
344. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [provider_id]=[8]
345. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(rate_flag=0)
346. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [rate_flag]=[0]
347. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(daily_call_credit_limit=0.00000)
348. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [daily_call_credit_limit]=[0.00000]
349. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(monthly_call_credit_limit=0.00000)
350. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [monthly_call_credit_limit]=[0.00000]
351. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(daily_call_used_limit=0.00000)
352. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [daily_call_used_limit]=[0.00000]
353. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(monthly_call_used_limit=0.00000)
354. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [monthly_call_used_limit]=[0.00000]
355. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(quality_trunk_seq=4)
356. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [quality_trunk_seq]=[4]
357. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(force_trunk_flag=0)
358. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [force_trunk_flag]=[0]
359. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(presence_data=trunk_id=4)
360. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[trunk_id=4]
361. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs set(intcall=0)
362. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_dptools.c:1671 SET sofia/default/sky111v6@tdsl.skytel.rs [intcall]=[0]
363. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(presence_id=381653552255)
364. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_id]=[381653552255]
365. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs export(presence_data=x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46)                                                                                                                                                                                      
366. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46]                                                                                                                                                            
367. EXECUTE [depth=0] sofia/default/sky111v6@tdsl.skytel.rs bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381653552255)
368. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [t38_passthru]=[true] to event
369. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [is_recording]=[1] to event
370. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [media_bug_answer_req]=[true] to event
371. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [record_sample_rate]=[8000] to event
372. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [execute_on_answer]=[record_session /usr/local/freeswitch/recordings/c62300b1-02ef-4df8-b418-f3bb95236bbb.wav] to event                                                                                                                                                                                  
373. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [presence_data]=[x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46] to event                                                                                                            
374. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [presence_id]=[381653552255] to event
375. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_channel.c:1288 sofia/default/sky111v6@tdsl.skytel.rs EXPORTING[export_vars] [presence_data]=[x|||SkyTel(3333456913)|||^38165.* // Srbija-Mobilni // 0.1200000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||103.240.35.46] to event                                                                                                            
376. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
377. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables
378. 2024-04-17 14:00:34.057506 94.67% [NOTICE] switch_channel.c:1142 New Channel sofia/default/381653552255 [23ac4750-7398-41ac-b5db-770275cb59db]
379. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_sofia.c:5110 (sofia/default/381653552255) State Change CS_NEW -> CS_INIT
380. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_ivr_originate.c:3167 sofia/default/381653552255 Setting leg timeout to 30
381. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_INIT (Cur 30 Tot 248045)
382. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381653552255) State INIT
383. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_sofia.c:97 sofia/default/381653552255 SOFIA INIT
384. 2024-04-17 14:00:34.057506 94.67% [INFO] sofia_glue.c:1659 sofia/default/381653552255 sending invite call-id: (null)
385. 2024-04-17 14:00:34.057506 94.67% [DEBUG] sofia_glue.c:1662 sofia/default/381653552255 sending invite version: 1.10.10-release  64bit
386. Local SDP:                                                                                                                                                                                                        
387. v=0                                                                                                                                                                                                                
388. o=FreeSWITCH 1713332258 1713332259 IN IP4 82.214.92.4                                                                                                                                                              
389. s=FreeSWITCH                                                                                                                                                                                                      
390. c=IN IP4 82.214.92.4                                                                                                                                                                                              
391. t=0 0                                                                                                                                                                                                              
392. m=audio 22976 RTP/AVP 8 0 101                                                                                                                                                                                      
393. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
394. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
395. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
396. a=fmtp:101 0-15                                                                                                                                                                                                    
397. a=ptime:20                                                                                                                                                                                                        
398. a=sendrecv                                                                                                                                                                                                        
399.  
400. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:40 sofia/default/381653552255 Standard INIT
401. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:48 (sofia/default/381653552255) State Change CS_INIT -> CS_ROUTING
402. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381653552255) State INIT going to sleep
403. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_ROUTING (Cur 30 Tot 248045)
404. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381653552255) State ROUTING
405. 2024-04-17 14:00:34.057506 94.67% [DEBUG] mod_sofia.c:158 sofia/default/381653552255 SOFIA ROUTING
406. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_ivr_originate.c:67 (sofia/default/381653552255) State Change CS_ROUTING -> CS_CONSUME_MEDIA
407. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381653552255) State ROUTING going to sleep
408. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_CONSUME_MEDIA (Cur 30 Tot 248045)
409. 2024-04-17 14:00:34.057506 94.67% [DEBUG] sofia.c:7493 Channel sofia/default/381653552255 entering state [calling][0]
410. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/381653552255) State CONSUME_MEDIA
411. 2024-04-17 14:00:34.057506 94.67% [DEBUG] switch_core_state_machine.c:659 (sofia/default/381653552255) State CONSUME_MEDIA going to sleep
412. 2024-04-17 14:00:34.997505 94.77% [DEBUG] sofia.c:7493 Channel sofia/default/381653552255 entering state [proceeding][183]
413. 2024-04-17 14:00:34.997505 94.77% [DEBUG] sofia.c:7503 Remote SDP:
414. v=0                                                                                                                                                                                                                
415. o=- 1713332258 1713332261 IN IP4 82.214.92.11                                                                                                                                                                      
416. s=Asterisk                                                                                                                                                                                                        
417. c=IN IP4 82.214.92.11                                                                                                                                                                                              
418. t=0 0                                                                                                                                                                                                              
419. m=audio 12106 RTP/AVP 8 0 101                                                                                                                                                                                      
420. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
421. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
422. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
423. a=fmtp:101 0-16                                                                                                                                                                                                    
424. a=ptime:20                                                                                                                                                                                                        
425. a=maxptime:140                                                                                                                                                                                                    
426.  
427. 2024-04-17 14:00:34.997505 94.77% [NOTICE] sofia.c:7506 Pre-Answer sofia/default/381653552255!
428. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_channel.c:3585 (sofia/default/381653552255) Callstate Change DOWN -> EARLY
429. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
430. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
431. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
432. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
433. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
434. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
435. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
436. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/381653552255 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
437. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_codec.c:111 sofia/default/381653552255 Original read codec set to PCMA:8
438. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
439. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:5856 sofia/default/381653552255 Set 2833 dtmf send payload to 101 recv payload to 101
440. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/381653552255] 10.44.44.50 port 22976 -> 82.214.92.11 port 12106 codec: 8 ms: 20
441. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
442. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8972 sofia/default/381653552255 Set 2833 dtmf send payload to 101
443. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8979 sofia/default/381653552255 Set 2833 dtmf receive payload to 101
444. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:9002 sofia/default/381653552255 Set rtp dtmf delay to 40
445. 2024-04-17 14:00:34.997505 94.77% [INFO] switch_ivr_originate.c:3858 Sending early media
446. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/sky111v6@tdsl.skytel.rs] 10.44.44.50 port 25942 -> 103.240.35.46 port 8000 codec: 8 ms: 20
447. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
448. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8972 sofia/default/sky111v6@tdsl.skytel.rs Set 2833 dtmf send payload to 101
449. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:8979 sofia/default/sky111v6@tdsl.skytel.rs Set 2833 dtmf receive payload to 101
450. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_core_media.c:9002 sofia/default/sky111v6@tdsl.skytel.rs Set rtp dtmf delay to 40
451. 2024-04-17 14:00:34.997505 94.77% [DEBUG] mod_sofia.c:2607 Ring SDP:
452. v=0                                                                                                                                                                                                                
453. o=FreeSWITCH 1713329292 1713329293 IN IP4 82.214.92.4                                                                                                                                                              
454. s=FreeSWITCH                                                                                                                                                                                                      
455. c=IN IP4 82.214.92.4                                                                                                                                                                                              
456. t=0 0                                                                                                                                                                                                              
457. m=audio 25942 RTP/AVP 8 101                                                                                                                                                                                        
458. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
459. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
460. a=fmtp:101 0-15                                                                                                                                                                                                    
461. a=ptime:20                                                                                                                                                                                                        
462. a=sendrecv                                                                                                                                                                                                        
463.  
464. 2024-04-17 14:00:34.997505 94.77% [NOTICE] mod_sofia.c:2610 Pre-Answer sofia/default/sky111v6@tdsl.skytel.rs!
465. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_channel.c:3585 (sofia/default/sky111v6@tdsl.skytel.rs) Callstate Change RINGING -> EARLY
466. 2024-04-17 14:00:34.997505 94.77% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/381653552255] Peer UUID: 23ac4750-7398-41ac-b5db-770275cb59db
467. 2024-04-17 14:00:34.997505 94.77% [DEBUG] sofia.c:7493 Channel sofia/default/sky111v6@tdsl.skytel.rs entering state [early][183]
468. 2024-04-17 14:00:35.017507 94.77% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/381653552255) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
469. 2024-04-17 14:00:35.017507 94.77% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_EXCHANGE_MEDIA (Cur 30 Tot 248045)
470. 2024-04-17 14:00:35.017507 94.77% [DEBUG] switch_core_state_machine.c:650 (sofia/default/381653552255) State EXCHANGE_MEDIA
471. 2024-04-17 14:00:35.017507 94.77% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
472. 2024-04-17 14:00:35.137505 94.77% [DEBUG] sofia.c:7493 Channel sofia/default/381653552255 entering state [proceeding][183]
473. 2024-04-17 14:00:35.137505 94.77% [DEBUG] sofia.c:7500 Duplicate SDP
474. v=0                                                                                                                                                                                                                
475. o=- 1713332258 1713332261 IN IP4 82.214.92.11                                                                                                                                                                      
476. s=Asterisk                                                                                                                                                                                                        
477. c=IN IP4 82.214.92.11                                                                                                                                                                                              
478. t=0 0                                                                                                                                                                                                              
479. m=audio 12106 RTP/AVP 8 0 101                                                                                                                                                                                      
480. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
481. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
482. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
483. a=fmtp:101 0-16                                                                                                                                                                                                    
484. a=ptime:20                                                                                                                                                                                                        
485. a=maxptime:140                                                                                                                                                                                                    
486.  
487. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
488. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
489. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
490. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
491. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
492. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
493. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
494. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
495. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:5856 sofia/default/381653552255 Set 2833 dtmf send payload to 101 recv payload to 101
496. 2024-04-17 14:00:35.137505 94.77% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/381653552255.
497. 2024-04-17 14:00:35.197505 94.77% [DEBUG] switch_ivr_bridge.c:654 sofia/default/381653552255 Bridge execute app sched_hangup(+6000 normal_clearing)
498. 2024-04-17 14:00:35.217504 94.77% [DEBUG] switch_ivr.c:632 sofia/default/381653552255 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
499. EXECUTE [depth=0] sofia/default/381653552255 sched_hangup(+6000 normal_clearing)
500. 2024-04-17 14:00:35.217504 94.77% [DEBUG] switch_scheduler.c:263 Added task 110336 switch_ivr_schedule_hangup (23ac4750-7398-41ac-b5db-770275cb59db) to run at 1713361235
501. 2024-04-17 14:00:35.397507 94.93% [INFO] switch_rtp.c:7463 Auto Changing audio port from 82.214.92.11:12106 to 10.90.90.10:12106
502. 2024-04-17 14:00:35.537506 94.93% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
503. 2024-04-17 14:00:36.737506 94.93% [DEBUG] switch_core_media.c:3190 alternate payload received (received 8, expecting 0)
504. 2024-04-17 14:00:36.737506 94.93% [WARNING] switch_core_media.c:3201 Changing current codec to PCMA (payload type 8).
505. 2024-04-17 14:00:36.737506 94.93% [DEBUG] sofia.c:7493 Channel sofia/default/390549911645 entering state [completing][200]
506. 2024-04-17 14:00:36.737506 94.93% [DEBUG] sofia.c:7500 Duplicate SDP
507. v=0                                                                                                                                                                                                                
508. o=didww-y 1713355225 1713355225 IN IP4 46.19.210.21                                                                                                                                                                
509. s=didww-y                                                                                                                                                                                                          
510. t=0 0                                                                                                                                                                                                              
511. m=audio 28034 RTP/AVP 8 0 101                                                                                                                                                                                      
512. c=IN IP4 46.19.210.21                                                                                                                                                                                              
513. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
514. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
515. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
516. a=fmtp:101 0-15                                                                                                                                                                                                    
517. a=ptime:20                                                                                                                                                                                                        
518. a=silenceSupp:off - - - -                                                                                                                                                                                          
519.  
520. 2024-04-17 14:00:36.757505 94.93% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMU@20ms@8000hz to PCMA@20ms@8000hz
521. 2024-04-17 14:00:36.777506 94.93% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/390549911645 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
522. 2024-04-17 14:00:36.777506 94.93% [DEBUG] switch_core_codec.c:124 sofia/default/390549911645 Original read codec replaced with PCMA:8
523. 2024-04-17 14:00:36.777506 94.93% [DEBUG] sofia.c:7493 Channel sofia/default/390549911645 entering state [ready][200]
524. 2024-04-17 14:00:36.777506 94.93% [NOTICE] sofia.c:8622 Channel [sofia/default/390549911645] has been answered
525. 2024-04-17 14:00:36.777506 94.93% [DEBUG] switch_channel.c:3912 (sofia/default/390549911645) Callstate Change EARLY -> ACTIVE
526. 2024-04-17 14:00:36.777506 94.93% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/Rado011Mara@10.44.44.50.
527. 2024-04-17 14:00:36.777506 94.93% [DEBUG] mod_sofia.c:914 Local SDP sofia/default/Rado011Mara@10.44.44.50:
528. v=0                                                                                                                                                                                                                
529. o=FreeSWITCH 1713332048 1713332050 IN IP4 82.214.92.4                                                                                                                                                              
530. s=FreeSWITCH                                                                                                                                                                                                      
531. c=IN IP4 82.214.92.4                                                                                                                                                                                              
532. t=0 0                                                                                                                                                                                                              
533. m=audio 23178 RTP/AVP 8 101                                                                                                                                                                                        
534. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
535. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
536. a=fmtp:101 0-15                                                                                                                                                                                                    
537. a=ptime:20                                                                                                                                                                                                        
538. a=sendrecv                                                                                                                                                                                                        
539.  
540. 2024-04-17 14:00:36.777506 94.93% [NOTICE] switch_ivr_bridge.c:730 Channel [sofia/default/Rado011Mara@10.44.44.50] has been answered
541. 2024-04-17 14:00:36.777506 94.93% [DEBUG] switch_channel.c:3912 (sofia/default/Rado011Mara@10.44.44.50) Callstate Change EARLY -> ACTIVE
542. 2024-04-17 14:00:36.777506 94.93% [DEBUG] sofia.c:7493 Channel sofia/default/Rado011Mara@10.44.44.50 entering state [completed][200]
543. 2024-04-17 14:00:36.797511 94.93% [DEBUG] sofia.c:7493 Channel sofia/default/Rado011Mara@10.44.44.50 entering state [ready][200]
544. 2024-04-17 14:00:36.817510 94.93% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
545. 2024-04-17 14:00:36.817510 94.93% [DEBUG] switch_core_media.c:3190 alternate payload received (received 0, expecting 8)
546. 2024-04-17 14:00:36.817510 94.93% [WARNING] switch_core_media.c:3201 Changing current codec to PCMU (payload type 0).
547. 2024-04-17 14:00:36.837506 94.93% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMA@20ms@8000hz to PCMU@20ms@8000hz
548. 2024-04-17 14:00:36.837506 94.93% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
549. 2024-04-17 14:00:36.857505 94.93% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/390549911645 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
550. 2024-04-17 14:00:36.857505 94.93% [DEBUG] switch_core_codec.c:124 sofia/default/390549911645 Original read codec replaced with PCMU:0
551. 2024-04-17 14:00:39.377504 94.90% [NOTICE] switch_channel.c:1142 New Channel sofia/default/381114419368@10.44.44.50 [344396a5-8d8d-4723-a50a-85688b444550]
552. 2024-04-17 14:00:39.377504 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_NEW (Cur 31 Tot 248046)
553. 2024-04-17 14:00:39.377504 94.90% [INFO] sofia.c:10459 sofia/default/381114419368@10.44.44.50 receiving invite from 82.214.92.6:60676 version: 1.10.10-release  64bit call-id: 1t6JMxZ8oX__jAwDFI3oFw..
554. 2024-04-17 14:00:39.377504 94.90% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 82.214.92.6:0.
555. 2024-04-17 14:00:39.377504 94.90% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [38166402602@10.44.44.50] from ip 82.214.92.6
556. 2024-04-17 14:00:39.377504 94.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/381114419368@10.44.44.50) State NEW
557. 2024-04-17 14:00:39.377504 94.90% [DEBUG] sofia.c:2419 detaching session 344396a5-8d8d-4723-a50a-85688b444550
558. 2024-04-17 14:00:39.377504 94.90% [DEBUG] sofia.c:2532 Re-attaching to session 344396a5-8d8d-4723-a50a-85688b444550
559. 2024-04-17 14:00:39.397504 94.90% [INFO] sofia.c:10459 sofia/default/381114419368@10.44.44.50 receiving invite from 82.214.92.6:60676 version: 1.10.10-release  64bit call-id: 1t6JMxZ8oX__jAwDFI3oFw..
560. 2024-04-17 14:00:39.397504 94.90% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 82.214.92.6:0.
561. 2024-04-17 14:00:39.397504 94.90% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
562. 2024-04-17 14:00:39.397504 94.90% [DEBUG] sofia.c:7493 Channel sofia/default/381114419368@10.44.44.50 entering state [received][100]
563. 2024-04-17 14:00:39.397504 94.90% [DEBUG] sofia.c:7503 Remote SDP:
564. v=0                                                                                                                                                                                                                
565. o=105 705 2782 IN IP4 10.10.10.176                                                                                                                                                                                
566. s=Talk                                                                                                                                                                                                            
567. c=IN IP4 10.66.66.50                                                                                                                                                                                              
568. t=0 0                                                                                                                                                                                                              
569. a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics                                                                                                                                          
570. m=audio 54020 RTP/AVP 96 97 98 0 8 9 101 99 100                                                                                                                                                                    
571. a=rtpmap:96 opus/48000/2                                                                                                                                                                                          
572. a=fmtp:96 useinbandfec=1                                                                                                                                                                                          
573. a=rtpmap:97 speex/16000                                                                                                                                                                                            
574. a=fmtp:97 vbr=on                                                                                                                                                                                                  
575. a=rtpmap:98 speex/8000                                                                                                                                                                                            
576. a=fmtp:98 vbr=on                                                                                                                                                                                                  
577. a=rtpmap:101 telephone-event/48000                                                                                                                                                                                
578. a=rtpmap:99 telephone-event/16000                                                                                                                                                                                  
579. a=rtpmap:100 telephone-event/8000                                                                                                                                                                                  
580. a=rtcp-fb:* trr-int 1000                                                                                                                                                                                          
581. a=rtcp-fb:* ccm tmmbr                                                                                                                                                                                              
582.  
583. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
584. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:96:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
585. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:97:16000:20:0:1]/[PCMA:8:8000:20:64000:1]
586. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:97:16000:20:0:1]/[PCMU:0:8000:20:64000:1]
587. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:98:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
588. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [speex:98:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
589. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
590. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
591. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
592. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
593. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
594. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
595. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
596. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
597. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@48000
598. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/381114419368@10.44.44.50 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
599. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_codec.c:111 sofia/default/381114419368@10.44.44.50 Original read codec set to PCMU:0
600. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 100@8000
601. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_media.c:5856 sofia/default/381114419368@10.44.44.50 Set 2833 dtmf send payload to 100 recv payload to 100
602. 2024-04-17 14:00:39.397504 94.90% [DEBUG] sofia.c:7927 (sofia/default/381114419368@10.44.44.50) State Change CS_NEW -> CS_INIT
603. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_INIT (Cur 31 Tot 248046)
604. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381114419368@10.44.44.50) State INIT
605. 2024-04-17 14:00:39.397504 94.90% [DEBUG] mod_sofia.c:97 sofia/default/381114419368@10.44.44.50 SOFIA INIT
606. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/381114419368@10.44.44.50 Standard INIT
607. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/381114419368@10.44.44.50) State Change CS_INIT -> CS_ROUTING
608. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381114419368@10.44.44.50) State INIT going to sleep
609. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_ROUTING (Cur 31 Tot 248046)
610. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_channel.c:2399 (sofia/default/381114419368@10.44.44.50) Callstate Change DOWN -> RINGING
611. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381114419368@10.44.44.50) State ROUTING
612. 2024-04-17 14:00:39.397504 94.90% [DEBUG] mod_sofia.c:158 sofia/default/381114419368@10.44.44.50 SOFIA ROUTING
613. 2024-04-17 14:00:39.397504 94.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/381114419368@10.44.44.50 Standard ROUTING
614. 2024-04-17 14:00:39.397504 94.90% [INFO] mod_dialplan_xml.c:639 Processing 105 <381114419368>->38166402602 in context default
615. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
616. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
617. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')                                                                                                                                                                                                
618. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
619. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 38166402602
620. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '38166402602' AND status=0 limit 1
621. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERStepMod011
622. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
623. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '38166402602' limit 1
624. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : 105 / 381114419368
625. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="38166402602" LIMIT 1                                    
626. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
627. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="38166402602" limit 1                                                                                                                                                                  
628. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
629. 2024-04-17 14:00:39.417505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9999235538]
630. 2024-04-17 14:00:39.417505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "9999235538" OR id = "9999235538") AND deleted = 0 limit 1
631. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^38166402602.*' OR blocked_patterns = '^3816640260.*' OR blocked_patterns = '^381664026.*' OR blocked_patterns = '^38166402.*' OR blocked_patterns = '^3816640.*' OR blocked_patterns = '^381664.*' OR blocked_patterns = '^38166.*' OR blocked_patterns = '^3816.*' OR blocked_patterns = '^381.*' OR blocked_patterns = '^38.*' OR blocked_patterns = '^3.*' OR blocked_patterns ='--') AND accountid = 51 limit 1                                                                        
632. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="38166402602" AND (accountid = 0 OR status = 1) LIMIT 1
633. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 4
634. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 4 AND status=0 limit 1                                                                                                                                                                                            
635. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*".....38166402602
636. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*".....38166402602
637. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"382/+38220690284/*/*,387/38733922284/*/*,381/381114419368/*/*,386/38618284878/*/*,49/4961088009984/*/*".....381114419368DBCLI38166402602                                                                                                                                                                                            
638. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : 381114419368DBCLI38166402602
639. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : 381114419368DBCLI38166402602
640. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"382/+38220690284/*/*,387/38733922284/*/*,381/381114419368/*/*,386/38618284878/*/*,49/4961088009984/*/*".....381114419368DBCLI38166402602                                                                                                                                                                                            
641. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : 381114419368DBCLI38166402602
642. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : 381114419368DBCLI38166402602
643. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"382/+38220690284/*/*,387/38733922284/*/*,381/381114419368/*/*,386/38618284878/*/*,49/4961088009984/*/*".....381114419368DBCLI38166402602                                                                                                                                                                                            
644. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : 381114419368DBCLI38166402602
645. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : 381114419368DBCLI38166402602
646. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"382/+38220690284/*/*,387/38733922284/*/*,381/381114419368/*/*,386/38618284878/*/*,49/4961088009984/*/*".....381114419368DBCLI38166402602                                                                                                                                                                                            
647. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : 381114419368DBCLI38166402602
648. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : 381114419368DBCLI38166402602
649. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"382/+38220690284/*/*,387/38733922284/*/*,381/381114419368/*/*,386/38618284878/*/*,49/4961088009984/*/*".....381114419368DBCLI38166402602                                                                                                                                                                                            
650. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before- Localization CLI/DST : 381114419368DBCLI38166402602
651. 2024-04-17 14:00:39.437505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After- Localization CLI/DST : 381114419368
652. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^38166402602.*' OR patterns = '^3816640260.*' OR patterns = '^381664026.*' OR patterns = '^38166402.*' OR patterns = '^3816640.*' OR patterns = '^381664.*' OR patterns = '^38166.*' OR patterns = '^3816.*' OR patterns = '^381.*' OR patterns = '^38.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 51 ORDER BY LENGTH(PKGPTR.patterns) DESC                                                                                                                                                                                                              
653. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="38166402602" AND A.accountid = '51' limit 1                                                                                                                                                                
654. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 38166402602
655. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="38166402602" LIMIT 1                                    
656. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
657. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="38166402602" limit 1                                                                                                                                                                  
658. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
659. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '38166402602' OR number = '3816640260' OR number = '381664026' OR number = '38166402' OR number = '3816640' OR number = '381664' OR number = '38166' OR number = '3816' OR number = '381' OR number = '38' OR number = '3' OR number ='--') OR (number = '38166402602*' OR number = '3816640260*' OR number = '381664026*' OR number = '38166402*' OR number = '3816640*' OR number = '381664*' OR number = '38166*' OR number = '3816*' OR number = '381*' OR number = '38*' OR number = '3*' OR number ='--') OR (number = '381114419368' OR number = '38111441936' OR number = '3811144193' OR number = '381114419' OR number = '38111441' OR number = '3811144' OR number = '381114' OR number = '38111' OR number = '3811' OR number = '381' OR number = '38' OR number = '3' OR number ='--') OR (number = '381114419368*' OR number = '38111441936*' OR number = '3811144193*' OR number = '381114419*' OR number = '38111441*' OR number = '3811144*' OR number = '381114*' OR number = '38111*' OR number = '3811*' OR number = '381*' OR number = '38*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC                                                                                                                            
660. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
661. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '38166402602' OR routing_prefix = '3816640260' OR routing_prefix = '381664026' OR routing_prefix = '38166402' OR routing_prefix = '3816640' OR routing_prefix = '381664' OR routing_prefix = '38166' OR routing_prefix = '3816' OR routing_prefix = '381' OR routing_prefix = '38' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1                                                                            
662. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
663. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 51
664. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9999235538
665. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 2981.235
666. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
667. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 5
668. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
669. 2024-04-17 14:00:39.437505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
670. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 222222222222222222222222222222222222222222222222222222222222
671. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 111111111111111111111111111111111111111111111111      
672. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 33333333333333333333333333333333333333333333333333333  
673. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
674. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
675. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
676. 2024-04-17 14:00:39.437505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^38166402602.*' OR pattern = '^3816640260.*' OR pattern = '^381664026.*' OR pattern = '^38166402.*' OR pattern = '^3816640.*' OR pattern = '^381664.*' OR pattern = '^38166.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND (caller_id_pattern = '^381114419368.*' OR caller_id_pattern = '^38111441936.*' OR caller_id_pattern = '^3811144193.*' OR caller_id_pattern = '^381114419.*' OR caller_id_pattern = '^38111441.*' OR caller_id_pattern = '^3811144.*' OR caller_id_pattern = '^381114.*' OR caller_id_pattern = '^38111.*' OR caller_id_pattern = '^3811.*' OR caller_id_pattern = '^381.*' OR caller_id_pattern = '^38.*' OR caller_id_pattern = '^3.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 5 OR accountid=51)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1                                                                                                                                                                                                    
677. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
678. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
679. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 698
680. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
681. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
682. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.0400000000
683. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Serbia-Mobile
684. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 164
685. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 51
686. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID: 0
687. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
688. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
689. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your2981.235 balance Accountid 51 !!!
690. 2024-04-17 14:00:39.457505 94.90% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
691. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
692. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
693. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=5 ORDER by id asc
694. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^38166402602.*' OR pattern = '^3816640260.*' OR pattern = '^381664026.*' OR pattern = '^38166402.*' OR pattern = '^3816640.*' OR pattern = '^381664.*' OR pattern = '^38166.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4,6,8,10,16,21) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC                                                                        
695. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [HYBRID_ROUTING] LCR With Cost In 2nd Stage
696. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
697. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15926
698. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^3816.*
699. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Serbia-Mobilni
700. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
701. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
702. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^3816.*
703. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
704. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
705. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 4
706. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Asterisk
707. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
708. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
709. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 8
710. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
711. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
712. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 51 AND status=0 LIMIT 1                                                                                                                                                                                        
713. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
714. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = 'StepMod011' and status = 0 limit 1
715. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
716. 2024-04-17 14:00:39.457505 94.90% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
717. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 5 AND status = 0
718. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 8) AND status=0 limit 1                                                                                                                                                                  
719. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1                                                                                                                                                                    
720. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="38166402602" LIMIT 1                                    
721. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
722. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
723. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
724. <document type="freeswitch/xml">                                                                                                                                                                                  
725. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
726. <context name="default">                                                                                                                                                                                          
727. <extension name="38166402602">                                                                                                                                                                                    
728. <condition field="destination_number" expression="38166402602">                                                                                                                                                    
729. <action application="set" data="effective_destination_number=38166402602"/>                                                                                                                                        
730. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>                                                                                                                                        
731. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>                                                                                                                              
732. <action application="set" data="notify_flag=0"/>                                                                                                                                                                  
733. <action application="set" data="notify_credit_limit=5.00000"/>                                                                                                                                                    
734. <action application="set" data="type=0"/>                                                                                                                                                                          
735. <action application="set" data="callstart=2024-04-17 12:00:39"/>                                                                                                                                                  
736. <action application="set" data="hangup_after_bridge=true"/>                                                                                                                                                        
737. <action application="set" data="continue_on_fail=TRUE"/>                                                                                                                                                          
738. <action application="set" data="account_id=51"/>                                                                                                                                                                  
739. <action application="set" data="parent_id=0"/>                                                                                                                                                                    
740. <action application="set" data="entity_id=0"/>                                                                                                                                                                    
741. <action application="set" data="call_processed=internal"/>                                                                                                                                                        
742. <action application="set" data="call_direction=outbound"/>                                                                                                                                                        
743. <action application="set" data="accountname=default"/>                                                                                                                                                            
744. <action application="export" data="t38_passthru=true"/>                                                                                                                                                            
745. <action application="set" data="fax_enable_t38=true"/>                                                                                                                                                            
746. <action application="set" data="fax_enable_t38_request=true"/>                                                                                                                                                    
747. <action application="set" data="origination_rates=ID:698|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:5|MARKUP:0|CI:164|ACCID:51"/>                                                                                                                                                                                                    
748. <action application="set" data="original_caller_id_name=105"/>                                                                                                                                                    
749. <action application="set" data="original_caller_id_number=381114419368"/>                                                                                                                                          
750.  
751. <action application="set" data="effective_caller_id_name=105"/>                                                                                                                                                    
752. <action application="set" data="effective_caller_id_number=381114419368"/>                                                                                                                                        
753. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
754. <action application="set" data="termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8"/>    
755. <action application="set" data="trunk_id=4"/>                                                                                                                                                                      
756. <action application="set" data="provider_id=8"/>                                                                                                                                                                  
757. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
758. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
759. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
760. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
761. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
762. <action application="set" data="quality_trunk_seq=4"/>                                                                                                                                                            
763. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
764. <action application="export" data="presence_data=trunk_id=4"/>                                                                                                                                                    
765. <action application="set" data="intcall=0"/>                                                                                                                                                                      
766. <action application="export" data="presence_id=38166402602"/>                                                                                                                                                      
767. <action application="export" data="presence_data=x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6"/>                                                                                                                                                                                                                  
768. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/38166402602"/>                                                                    
769. </condition>                                                                                                                                                                                                      
770. </extension>                                                                                                                                                                                                      
771. </context>                                                                                                                                                                                                        
772. </section>                                                                                                                                                                                                        
773. </document>                                                                                                                                                                                                        
774. Dialplan: sofia/default/381114419368@10.44.44.50 parsing [default->38166402602] continue=false
775. Dialplan: sofia/default/381114419368@10.44.44.50 Regex (PASS) [38166402602] destination_number(38166402602) =~ /38166402602/ break=on-false
776. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(effective_destination_number=38166402602)
777. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(bridge_pre_execute_bleg_app=sched_hangup)
778. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
779. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(notify_flag=0)
780. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(notify_credit_limit=5.00000)
781. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(type=0)
782. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(callstart=2024-04-17 12:00:39)
783. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(hangup_after_bridge=true)
784. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(continue_on_fail=TRUE)
785. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(account_id=51)
786. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(parent_id=0)
787. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(entity_id=0)
788. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(call_processed=internal)
789. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(call_direction=outbound)
790. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(accountname=default)
791. Dialplan: sofia/default/381114419368@10.44.44.50 Action export(t38_passthru=true)
792. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(fax_enable_t38=true)
793. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(fax_enable_t38_request=true)
794. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(origination_rates=ID:698|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:5|MARKUP:0|CI:164|ACCID:51)                                                                                                                                                                          
795. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(original_caller_id_name=105)
796. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(original_caller_id_number=381114419368)
797. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(effective_caller_id_name=105)
798. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(effective_caller_id_number=381114419368)
799. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(calltype=STANDARD)
800. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                              
801. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(trunk_id=4)
802. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(provider_id=8)
803. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(rate_flag=0)
804. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(daily_call_credit_limit=0.00000)
805. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(monthly_call_credit_limit=0.00000)
806. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(daily_call_used_limit=0.00000)
807. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(monthly_call_used_limit=0.00000)
808. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(quality_trunk_seq=4)
809. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(force_trunk_flag=0)
810. Dialplan: sofia/default/381114419368@10.44.44.50 Action export(presence_data=trunk_id=4)
811. Dialplan: sofia/default/381114419368@10.44.44.50 Action set(intcall=0)
812. Dialplan: sofia/default/381114419368@10.44.44.50 Action export(presence_id=38166402602)
813. Dialplan: sofia/default/381114419368@10.44.44.50 Action export(presence_data=x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6)                                                                                                                                                                                        
814. Dialplan: sofia/default/381114419368@10.44.44.50 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/38166402602)
815. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_core_state_machine.c:281 (sofia/default/381114419368@10.44.44.50) State Change CS_ROUTING -> CS_EXECUTE
816. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381114419368@10.44.44.50) State ROUTING going to sleep
817. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_EXECUTE (Cur 31 Tot 248046)
818. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_core_state_machine.c:647 (sofia/default/381114419368@10.44.44.50) State EXECUTE
819. 2024-04-17 14:00:39.457505 94.90% [DEBUG] mod_sofia.c:213 sofia/default/381114419368@10.44.44.50 SOFIA EXECUTE
820. 2024-04-17 14:00:39.457505 94.90% [DEBUG] switch_core_state_machine.c:323 sofia/default/381114419368@10.44.44.50 Standard EXECUTE
821. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(effective_destination_number=38166402602)
822. 2024-04-17 14:00:39.457505 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [effective_destination_number]=[38166402602]
823. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(bridge_pre_execute_bleg_app=sched_hangup)
824. 2024-04-17 14:00:39.457505 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [bridge_pre_execute_bleg_app]=[sched_hangup]
825. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
826. 2024-04-17 14:00:39.457505 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
827. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(notify_flag=0)
828. 2024-04-17 14:00:39.457505 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [notify_flag]=[0]
829. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(notify_credit_limit=5.00000)
830. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [notify_credit_limit]=[5.00000]
831. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(type=0)
832. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [type]=[0]
833. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(callstart=2024-04-17 12:00:39)
834. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [callstart]=[2024-04-17 12:00:39]
835. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(hangup_after_bridge=true)
836. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [hangup_after_bridge]=[true]
837. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(continue_on_fail=TRUE)
838. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [continue_on_fail]=[TRUE]
839. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(account_id=51)
840. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [account_id]=[51]
841. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(parent_id=0)
842. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [parent_id]=[0]
843. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(entity_id=0)
844. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [entity_id]=[0]
845. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(call_processed=internal)
846. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [call_processed]=[internal]
847. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(call_direction=outbound)
848. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [call_direction]=[outbound]
849. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(accountname=default)
850. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [accountname]=[default]
851. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 export(t38_passthru=true)
852. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [t38_passthru]=[true]
853. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(fax_enable_t38=true)
854. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [fax_enable_t38]=[true]
855. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(fax_enable_t38_request=true)
856. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [fax_enable_t38_request]=[true]
857. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(origination_rates=ID:698|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:5|MARKUP:0|CI:164|ACCID:51)                                                                                                                                                                        
858. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [origination_rates]=[ID:698|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:5|MARKUP:0|CI:164|ACCID:51]                                                                                                                          
859. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(original_caller_id_name=105)
860. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [original_caller_id_name]=[105]
861. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(original_caller_id_number=381114419368)
862. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [original_caller_id_number]=[381114419368]
863. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(effective_caller_id_name=105)
864. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [effective_caller_id_name]=[105]
865. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(effective_caller_id_number=381114419368)
866. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [effective_caller_id_number]=[381114419368]
867. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(calltype=STANDARD)
868. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [calltype]=[STANDARD]
869. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                            
870. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [termination_rates]=[ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8]                                                                                                                                              
871. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(trunk_id=4)
872. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [trunk_id]=[4]
873. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(provider_id=8)
874. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [provider_id]=[8]
875. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(rate_flag=0)
876. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [rate_flag]=[0]
877. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(daily_call_credit_limit=0.00000)
878. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [daily_call_credit_limit]=[0.00000]
879. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(monthly_call_credit_limit=0.00000)
880. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [monthly_call_credit_limit]=[0.00000]
881. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(daily_call_used_limit=0.00000)
882. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [daily_call_used_limit]=[0.00000]
883. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(monthly_call_used_limit=0.00000)
884. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [monthly_call_used_limit]=[0.00000]
885. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(quality_trunk_seq=4)
886. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [quality_trunk_seq]=[4]
887. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(force_trunk_flag=0)
888. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [force_trunk_flag]=[0]
889. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 export(presence_data=trunk_id=4)
890. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[trunk_id=4]
891. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 set(intcall=0)
892. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_dptools.c:1671 SET sofia/default/381114419368@10.44.44.50 [intcall]=[0]
893. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 export(presence_id=38166402602)
894. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_id]=[38166402602]
895. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 export(presence_data=x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6)                                                                                                                                                                                      
896. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6]                                                                                                                                                              
897. EXECUTE [depth=0] sofia/default/381114419368@10.44.44.50 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/38166402602)
898. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1288 sofia/default/381114419368@10.44.44.50 EXPORTING[export_vars] [t38_passthru]=[true] to event
899. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1288 sofia/default/381114419368@10.44.44.50 EXPORTING[export_vars] [presence_data]=[x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6] to event                                                                                                            
900. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1288 sofia/default/381114419368@10.44.44.50 EXPORTING[export_vars] [presence_id]=[38166402602] to event
901. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_channel.c:1288 sofia/default/381114419368@10.44.44.50 EXPORTING[export_vars] [presence_data]=[x|||StepMode(9999235538)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6] to event                                                                                                            
902. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
903. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables
904. 2024-04-17 14:00:39.477534 94.90% [NOTICE] switch_channel.c:1142 New Channel sofia/default/38166402602 [3c2c4a07-23ad-49b7-942b-b1c1b70198ab]
905. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_sofia.c:5110 (sofia/default/38166402602) State Change CS_NEW -> CS_INIT
906. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_ivr_originate.c:3167 sofia/default/38166402602 Setting leg timeout to 30
907. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38166402602) Running State Change CS_INIT (Cur 32 Tot 248047)
908. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/38166402602) State INIT
909. 2024-04-17 14:00:39.477534 94.90% [DEBUG] mod_sofia.c:97 sofia/default/38166402602 SOFIA INIT
910. 2024-04-17 14:00:39.477534 94.90% [INFO] sofia_glue.c:1659 sofia/default/38166402602 sending invite call-id: (null)
911. 2024-04-17 14:00:39.477534 94.90% [DEBUG] sofia_glue.c:1662 sofia/default/38166402602 sending invite version: 1.10.10-release  64bit
912. Local SDP:                                                                                                                                                                                                        
913. v=0                                                                                                                                                                                                                
914. o=FreeSWITCH 1713330251 1713330252 IN IP4 82.214.92.4                                                                                                                                                              
915. s=FreeSWITCH                                                                                                                                                                                                      
916. c=IN IP4 82.214.92.4                                                                                                                                                                                              
917. t=0 0                                                                                                                                                                                                              
918. m=audio 24988 RTP/AVP 8 0 101                                                                                                                                                                                      
919. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
920. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
921. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
922. a=fmtp:101 0-15                                                                                                                                                                                                    
923. a=ptime:20                                                                                                                                                                                                        
924. a=sendrecv                                                                                                                                                                                                        
925.  
926. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/38166402602 Standard INIT
927. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/38166402602) State Change CS_INIT -> CS_ROUTING
928. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/38166402602) State INIT going to sleep
929. 2024-04-17 14:00:39.477534 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38166402602) Running State Change CS_ROUTING (Cur 32 Tot 248047)
930. 2024-04-17 14:00:39.497510 94.90% [DEBUG] sofia.c:7493 Channel sofia/default/38166402602 entering state [calling][0]
931. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/38166402602) State ROUTING
932. 2024-04-17 14:00:39.497510 94.90% [DEBUG] mod_sofia.c:158 sofia/default/38166402602 SOFIA ROUTING
933. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_ivr_originate.c:67 (sofia/default/38166402602) State Change CS_ROUTING -> CS_CONSUME_MEDIA
934. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/38166402602) State ROUTING going to sleep
935. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38166402602) Running State Change CS_CONSUME_MEDIA (Cur 32 Tot 248047)
936. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/38166402602) State CONSUME_MEDIA
937. 2024-04-17 14:00:39.497510 94.90% [DEBUG] switch_core_state_machine.c:659 (sofia/default/38166402602) State CONSUME_MEDIA going to sleep
938. 2024-04-17 14:00:41.337505 94.87% [NOTICE] switch_channel.c:1142 New Channel sofia/default/Pab011Med@10.44.44.50 [bd6df52e-c36d-4b90-a968-8c7ef4441b92]
939. 2024-04-17 14:00:41.337505 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/Pab011Med@10.44.44.50) Running State Change CS_NEW (Cur 33 Tot 248048)
940. 2024-04-17 14:00:41.337505 94.87% [INFO] sofia.c:10459 sofia/default/Pab011Med@10.44.44.50 receiving invite from 82.214.92.6:60676 version: 1.10.10-release  64bit call-id: 62SSYJuFSaC10lGD6xVqGA..
941. 2024-04-17 14:00:41.337505 94.87% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 82.214.92.6:0.
942. 2024-04-17 14:00:41.357506 94.87% [WARNING] sofia_reg.c:1842 SIP auth challenge (INVITE) on sofia profile 'default' for [0606257565@10.44.44.50] from ip 82.214.92.6
943. 2024-04-17 14:00:41.357506 94.87% [DEBUG] switch_core_state_machine.c:600 (sofia/default/Pab011Med@10.44.44.50) State NEW
944. 2024-04-17 14:00:41.357506 94.87% [DEBUG] sofia.c:2419 detaching session bd6df52e-c36d-4b90-a968-8c7ef4441b92
945. 2024-04-17 14:00:41.357506 94.87% [DEBUG] sofia.c:2532 Re-attaching to session bd6df52e-c36d-4b90-a968-8c7ef4441b92
946. 2024-04-17 14:00:41.377507 94.87% [INFO] sofia.c:10459 sofia/default/Pab011Med@10.44.44.50 receiving invite from 82.214.92.6:60676 version: 1.10.10-release  64bit call-id: 62SSYJuFSaC10lGD6xVqGA..
947. 2024-04-17 14:00:41.377507 94.87% [DEBUG] sofia.c:10553 verifying acl "default" for ip/port 82.214.92.6:0.
948. 2024-04-17 14:00:41.377507 94.87% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
949. 2024-04-17 14:00:41.377507 94.87% [DEBUG] sofia.c:7493 Channel sofia/default/Pab011Med@10.44.44.50 entering state [received][100]
950. 2024-04-17 14:00:41.377507 94.87% [DEBUG] sofia.c:7503 Remote SDP:
951. v=0                                                                                                                                                                                                                
952. o=- 1651947676889440541 2 IN IP4 127.0.0.1                                                                                                                                                                        
953. s=-                                                                                                                                                                                                                
954. c=IN IP4 10.66.66.50                                                                                                                                                                                              
955. t=0 0                                                                                                                                                                                                              
956. m=audio 53880 RTP/AVP 18 8 0 105 101                                                                                                                                                                              
957. c=IN IP4 10.66.66.50                                                                                                                                                                                              
958. a=rtpmap:18 G729/8000                                                                                                                                                                                              
959. a=fmtp:18 annexb=no                                                                                                                                                                                                
960. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
961. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
962. a=rtpmap:105 opus/48000/2                                                                                                                                                                                          
963. a=fmtp:105 minptime=10;useinbandfec=1                                                                                                                                                                              
964. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
965. a=mid:audio                                                                                                                                                                                                        
966. a=rtcp-fb:105 transport-cc                                                                                                                                                                                        
967. a=ssrc:1538885811 cname:Rn+jDwuHbTrgKaQr                                                                                                                                                                          
968.  
969. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
970. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
971. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
972. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
973. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
974. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
975. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
976. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
977. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:105:48000:20:0:2]/[PCMA:8:8000:20:64000:1]
978. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [opus:105:48000:20:0:2]/[PCMU:0:8000:20:64000:1]
979. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
980. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/Pab011Med@10.44.44.50 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
981. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_codec.c:111 sofia/default/Pab011Med@10.44.44.50 Original read codec set to PCMA:8
982. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
983. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_media.c:5856 sofia/default/Pab011Med@10.44.44.50 Set 2833 dtmf send payload to 101 recv payload to 101
984. 2024-04-17 14:00:41.377507 94.87% [DEBUG] sofia.c:7927 (sofia/default/Pab011Med@10.44.44.50) State Change CS_NEW -> CS_INIT
985. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/Pab011Med@10.44.44.50) Running State Change CS_INIT (Cur 33 Tot 248048)
986. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/Pab011Med@10.44.44.50) State INIT
987. 2024-04-17 14:00:41.377507 94.87% [DEBUG] mod_sofia.c:97 sofia/default/Pab011Med@10.44.44.50 SOFIA INIT
988. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:40 sofia/default/Pab011Med@10.44.44.50 Standard INIT
989. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:48 (sofia/default/Pab011Med@10.44.44.50) State Change CS_INIT -> CS_ROUTING
990. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/Pab011Med@10.44.44.50) State INIT going to sleep
991. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/Pab011Med@10.44.44.50) Running State Change CS_ROUTING (Cur 33 Tot 248048)
992. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_channel.c:2399 (sofia/default/Pab011Med@10.44.44.50) Callstate Change DOWN -> RINGING
993. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/Pab011Med@10.44.44.50) State ROUTING
994. 2024-04-17 14:00:41.377507 94.87% [DEBUG] mod_sofia.c:158 sofia/default/Pab011Med@10.44.44.50 SOFIA ROUTING
995. 2024-04-17 14:00:41.377507 94.87% [DEBUG] switch_core_state_machine.c:230 sofia/default/Pab011Med@10.44.44.50 Standard ROUTING
996. 2024-04-17 14:00:41.377507 94.87% [INFO] mod_dialplan_xml.c:639 Processing Maja B <Pab011Med>->0606257565 in context default
997. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
998. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
999. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','international_prefixes','interconnect_code')                                                                                                                                                                                                
1000. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1001. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Dialed number : 0606257565
1002. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT access_number FROM accessnumber WHERE access_number = '0606257565' AND status=0 limit 1
1003. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] SOURCE NUMBERPab011Med
1004. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1005. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION LOCAL SOURCE] Query :SELECT accountid FROM sip_devices WHERE username = '0606257565' limit 1
1006. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Caller Id name / number  : Maja B / 381114419417
1007. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0606257565" LIMIT 1                                      
1008. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1009. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="0606257565" limit 1                                                                                                                                                                    
1010. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Call direction : outbound
1011. 2024-04-17 14:00:41.397507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Accountcode : 9999296736]
1012. 2024-04-17 14:00:41.397507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [DOAUTHORIZATION] Query :SELECT * FROM accounts WHERE (number = "9999296736" OR id = "9999296736") AND deleted = 0 limit 1
1013. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_BLOCKED_PREFIX] Query :SELECT * FROM block_patterns WHERE (blocked_patterns = '^0606257565.*' OR blocked_patterns = '^060625756.*' OR blocked_patterns = '^06062575.*' OR blocked_patterns = '^0606257.*' OR blocked_patterns = '^060625.*' OR blocked_patterns = '^06062.*' OR blocked_patterns = '^0606.*' OR blocked_patterns = '^060.*' OR blocked_patterns = '^06.*' OR blocked_patterns = '^0.*' OR blocked_patterns ='--') AND accountid = 66 limit 1                                                                                                                
1014. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [IS_CHECK_DID] Query :SELECT * FROM dids WHERE number ="0606257565" AND (accountid = 0 OR status = 1) LIMIT 1
1015. 2024-04-17 14:00:41.417507 94.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] Localization Id: 3
1016. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,in_caller_id_originate,out_caller_id_originate,number_originate,dst_base_cid FROM localization WHERE id = 3 AND status=0 limit 1                                                                                                                                                                                            
1017. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....0606257565
1018. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....0606257565
1019. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :"00/*/*/*,+/*/*/*,0/381/*/*".....0606257565
1020. 2024-04-17 14:00:41.417507 94.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] Before Localization CLI/DST : 0606257565
1021. 2024-04-17 14:00:41.417507 94.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [DONUMBERTRANSLATION] After Localization CLI/DST : 381606257565
1022. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PACKAGE_INFO] Query :SELECT *,P.id as package_id,P.product_id as product_id FROM packages_view as P inner join package_patterns as PKGPTR on P.product_id = PKGPTR.product_id WHERE P.is_terminated = 0 AND (patterns = '^381606257565.*' OR patterns = '^38160625756.*' OR patterns = '^3816062575.*' OR patterns = '^381606257.*' OR patterns = '^38160625.*' OR patterns = '^3816062.*' OR patterns = '^381606.*' OR patterns = '^38160.*' OR patterns = '^3816.*' OR patterns = '^381.*' OR patterns = '^38.*' OR patterns = '^3.*' OR patterns ='--') AND accountid = 66 ORDER BY LENGTH(PKGPTR.patterns) DESC                                                                                                                                                                              
1023. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_SPEEDDIAL] Query :SELECT A.number FROM speed_dial as A,accounts as B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.speed_num ="381606257565" AND A.accountid = '66' limit 1                                                                                                                                                                
1024. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] SPEED DIAL NUMBER : 381606257565
1025. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="381606257565" LIMIT 1                                    
1026. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1027. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_LOCAL_CALL] Query :SELECT sip_devices.username as username,accounts.number as accountcode,sip_devices.accountid as accountid,accounts.did_cid_translation as did_cid_translation,sip_devices.codec as sip_codec FROM sip_devices as sip_devices,accounts as  accounts WHERE accounts.status=0 AND accounts.deleted=0 AND accounts.id=sip_devices.accountid AND sip_devices.username="381606257565" limit 1                                                                                                                                                                  
1028. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [Dialplan] New Call Direction : outbound
1029. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_CALL_BARRING] Query :SELECT number,RIGHT(number,1) as lastchar,type as status_type,number_type,accountid FROM call_barring WHERE ((number = '381606257565' OR number = '38160625756' OR number = '3816062575' OR number = '381606257' OR number = '38160625' OR number = '3816062' OR number = '381606' OR number = '38160' OR number = '3816' OR number = '381' OR number = '38' OR number = '3' OR number ='--') OR (number = '381606257565*' OR number = '38160625756*' OR number = '3816062575*' OR number = '381606257*' OR number = '38160625*' OR number = '3816062*' OR number = '381606*' OR number = '38160*' OR number = '3816*' OR number = '381*' OR number = '38*' OR number = '3*' OR number ='--') OR (number = '381114419417' OR number = '38111441941' OR number = '3811144194' OR number = '381114419' OR number = '38111441' OR number = '3811144' OR number = '381114' OR number = '38111' OR number = '3811' OR number = '381' OR number = '38' OR number = '3' OR number ='--') OR (number = '381114419417*' OR number = '38111441941*' OR number = '3811144194*' OR number = '381114419*' OR number = '38111441*' OR number = '3811144*' OR number = '381114*' OR number = '38111*' OR number = '3811*' OR number = '381*' OR number = '38*' OR number = '3*' OR number ='--')) AND status=0 order by LENGTH(number) DESC,lastchar DESC, modified_date DESC                                                                    
1030. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] Call Barring Status : -1
1031. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_ROUTING_INFO] Query :select id,routing_prefix,pricelist_id_admin from pricelists WHERE (routing_prefix = '381606257565' OR routing_prefix = '38160625756' OR routing_prefix = '3816062575' OR routing_prefix = '381606257' OR routing_prefix = '38160625' OR routing_prefix = '3816062' OR routing_prefix = '381606' OR routing_prefix = '38160' OR routing_prefix = '3816' OR routing_prefix = '381' OR routing_prefix = '38' OR routing_prefix = '3' OR routing_prefix ='--') AND status = 0 and reseller_id = 0 limit 1                                          
1032. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Account Information ===================
1033. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] User id : 66
1034. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Account code : 9999296736
1035. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Balance : 4999.665
1036. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Type : 1 [0:prepaid,1:postpaid]
1037. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Ratecard id : 4
1038. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] CID Pool : 0
1039. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================================================
1040. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 222222222222222222222222222222222222222222222222222222222222
1041. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 111111111111111111111111111111111111111111111111      
1042. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] 33333333333333333333333333333333333333333333333333333  
1043. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
1044. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] call_direction :outbound
1045. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
1046. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_RATES] Query :SELECT * FROM routes WHERE (pattern = '^381606257565.*' OR pattern = '^38160625756.*' OR pattern = '^3816062575.*' OR pattern = '^381606257.*' OR pattern = '^38160625.*' OR pattern = '^3816062.*' OR pattern = '^381606.*' OR pattern = '^38160.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND (caller_id_pattern = '^381114419417.*' OR caller_id_pattern = '^38111441941.*' OR caller_id_pattern = '^3811144194.*' OR caller_id_pattern = '^381114419.*' OR caller_id_pattern = '^38111441.*' OR caller_id_pattern = '^3811144.*' OR caller_id_pattern = '^381114.*' OR caller_id_pattern = '^38111.*' OR caller_id_pattern = '^3811.*' OR caller_id_pattern = '^381.*' OR caller_id_pattern = '^38.*' OR caller_id_pattern = '^3.*' OR caller_id_pattern ='^.*') AND status = 0 AND effective_date <= UTC_TIMESTAMP() AND (pricelist_id = 4 OR accountid=66)  ORDER BY accountid DESC,LENGTH(pattern) DESC,LENGTH(caller_id_pattern) DESC,cost DESC,effective_date DESC LIMIT 1                                                                                                                                                                    
1047. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP]  Call Direction : outbound
1048. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Rates Information ===================
1049. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 687
1050. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
1051. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Includedseconds : 0
1052. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Cost : 0.0400000000
1053. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Comment : Serbia-Mobile
1054. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Country Id : 164
1055. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Accid : 66
1056. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Trunk ID:
1057. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Routing type:
1058. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ================================================================
1059. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Your4999.665 balance Accountid 66 !!!
1060. 2024-04-17 14:00:41.417507 94.87% [NOTICE] switch_cpp.cpp:1465 [ASTPP] [FIND_MAXLENGTH] Limiting Call To Config Max Length 100 mins!
1061. 2024-04-17 14:00:41.417507 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Call Max Length Duration : 100 minutes
1062. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query Rate Carrier Id :
1063. 2024-04-17 14:00:41.417507 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES_TRUNKS] Query :SELECT GROUP_CONCAT(trunk_id) as ids FROM routing WHERE pricelist_id=4 ORDER by id asc
1064. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_CARRIER_RATES] Query :SELECT TK.id as trunk_id,TK.name as trunk_name,TK.sip_cid_type,TK.codec,GW.name as path,GW.dialplan_variable,TK.provider_id,TR.init_inc,TK.status,TK.maxchannels,TK.cps,TK.leg_timeout,TR.pattern,TR.id as outbound_route_id,TR.connectcost,TR.comment,TR.includedseconds,TR.cost,TR.inc,TR.prepend,TR.strip,(select name from gateways where status=0 AND id = TK.failover_gateway_id) as path1,(select name from gateways where status=0 AND id = TK.failover_gateway_id1) as path2 FROM outbound_routes as TR,trunks as TK,gateways as GW WHERE GW.status=0 AND GW.id= TK.gateway_id AND TK.status=0 AND TK.id= TR.trunk_id AND (pattern = '^381606257565.*' OR pattern = '^38160625756.*' OR pattern = '^3816062575.*' OR pattern = '^381606257.*' OR pattern = '^38160625.*' OR pattern = '^3816062.*' OR pattern = '^381606.*' OR pattern = '^38160.*' OR pattern = '^3816.*' OR pattern = '^381.*' OR pattern = '^38.*' OR pattern = '^3.*' OR pattern ='--') AND TR.status = 0  AND TR.trunk_id IN (4) AND TR.effective_date <= UTC_TIMESTAMP() ORDER by LENGTH (pattern) DESC,TR.cost ASC,TR.precedence ASC, TK.precedence,TR.effective_date DESC                                                      
1065. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [HYBRID_ROUTING] LCR With Cost In 2nd Stage
1066. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] =============== Termination Rates Information ===================
1067. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ID : 15926
1068. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Code : ^3816.*
1069. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Destination : Serbia-Mobilni
1070. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Connectcost : 0.0000000000
1071. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Free Seconds : 0
1072. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Prefix : ^3816.*
1073. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Strip :
1074. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Prepend :
1075. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Carrier id : 4
1076. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] carrier_name : Asterisk
1077. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] dialplan_variable :
1078. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Failover gateway :
1079. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Vendor id : 8
1080. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] Max channels : 0
1081. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] ========================END OF TERMINATION RATES=======================
1082. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_OVERRIDE_CALLERID] Query :SELECT callerid_name as cid_name,callerid_number as cid_number,accountid FROM accounts_callerid WHERE accountid = 66 AND status=0 LIMIT 1                                                                                                                                                                                        
1083. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP]  Maxlength : 100
1084. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [get_sip_device_info_for_did_pstn_call] Query :SELECT username FROM sip_devices WHERE username = 'Pab011Med' and status = 0 limit 1
1085. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1086. 2024-04-17 14:00:41.437505 94.87% [INFO] switch_cpp.cpp:1465 [ASTPP] [XML] Customer Recording Flag : 1
1087. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_PRICELIST_INFO] Query :select * from pricelists WHERE id = 4 AND status = 0
1088. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from accounts where id = 8) AND status=0 limit 1                                                                                                                                                                  
1089. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [GET_LOCALIZATION] Query :SELECT id,out_caller_id_terminate,number_terminate,dst_base_cid FROM localization WHERE id=(SELECT localization_id from trunks where id = 4) AND status=0 limit 1                                                                                                                                                                    
1090. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CHECK_DID] Query :SELECT A.id as id,A.number as did_number,B.id as accountid,B.number as account_code,A.number as did_number,A.connectcost,A.includedseconds,A.cost,A.inc,A.extensions,A.maxchannels,A.call_type,A.city,A.province,A.init_inc,A.leg_timeout,A.status,A.country_id,A.call_type_vm_flag, A.codec as did_codec,A.provider_id as provider_id,A.always as always,A.always_destination as always_destination FROM dids AS A,accounts AS B WHERE B.status=0 AND B.deleted=0 AND B.id=A.accountid AND A.number ="0606257565" LIMIT 1                                      
1091. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
1092. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [Dialplan] Generated XML:
1093. <?xml version="1.0" encoding="UTF-8" standalone="no"?>                                                                                                                                                            
1094. <document type="freeswitch/xml">                                                                                                                                                                                  
1095. <section name="dialplan" description="ASTPP Dialplan">                                                                                                                                                            
1096. <context name="default">                                                                                                                                                                                          
1097. <extension name="381606257565">                                                                                                                                                                                    
1098. <condition field="destination_number" expression="0606257565">                                                                                                                                                    
1099. <action application="set" data="effective_destination_number=381606257565"/>                                                                                                                                      
1100. <action application="set" data="bridge_pre_execute_bleg_app=sched_hangup"/>                                                                                                                                        
1101. <action application="set" data="bridge_pre_execute_bleg_data=+6000 normal_clearing"/>                                                                                                                              
1102. <action application="set" data="notify_flag=0"/>                                                                                                                                                                  
1103. <action application="set" data="notify_credit_limit=5.00000"/>                                                                                                                                                    
1104. <action application="set" data="type=0"/>                                                                                                                                                                          
1105. <action application="set" data="callstart=2024-04-17 12:00:41"/>                                                                                                                                                  
1106. <action application="set" data="hangup_after_bridge=true"/>                                                                                                                                                        
1107. <action application="set" data="continue_on_fail=TRUE"/>                                                                                                                                                          
1108. <action application="set" data="account_id=66"/>                                                                                                                                                                  
1109. <action application="set" data="parent_id=0"/>                                                                                                                                                                    
1110. <action application="set" data="entity_id=0"/>                                                                                                                                                                    
1111. <action application="set" data="call_processed=internal"/>                                                                                                                                                        
1112. <action application="set" data="call_direction=outbound"/>                                                                                                                                                        
1113. <action application="set" data="accountname=default"/>                                                                                                                                                            
1114. <action application="export" data="t38_passthru=true"/>                                                                                                                                                            
1115. <action application="set" data="fax_enable_t38=true"/>                                                                                                                                                            
1116. <action application="set" data="fax_enable_t38_request=true"/>                                                                                                                                                    
1117. <action application="set" data="origination_rates=ID:687|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:4|MARKUP:0|CI:164|ACCID:66"/>                                                                                                                                                                                                    
1118. <action application="set" data="original_caller_id_name=Maja B"/>                                                                                                                                                  
1119. <action application="set" data="original_caller_id_number=381114419417"/>                                                                                                                                          
1120.  
1121. <action application="set" data="effective_caller_id_name=Maja B"/>                                                                                                                                                
1122. <action application="set" data="effective_caller_id_number=381114419417"/>                                                                                                                                        
1123. <action application="set" data="calltype=STANDARD"/>                                                                                                                                                              
1124. <action application="set" data="termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8"/>    
1125. <action application="set" data="trunk_id=4"/>                                                                                                                                                                      
1126. <action application="set" data="provider_id=8"/>                                                                                                                                                                  
1127. <action application="set" data="rate_flag=0"/>                                                                                                                                                                    
1128. <action application="set" data="daily_call_credit_limit=0.00000"/>                                                                                                                                                
1129. <action application="set" data="monthly_call_credit_limit=0.00000"/>                                                                                                                                              
1130. <action application="set" data="daily_call_used_limit=0.00000"/>                                                                                                                                                  
1131. <action application="set" data="monthly_call_used_limit=0.00000"/>                                                                                                                                                
1132. <action application="set" data="quality_trunk_seq=4"/>                                                                                                                                                            
1133. <action application="set" data="force_trunk_flag=0"/>                                                                                                                                                              
1134. <action application="export" data="presence_data=trunk_id=4"/>                                                                                                                                                    
1135. <action application="set" data="intcall=0"/>                                                                                                                                                                      
1136. <action application="export" data="presence_id=381606257565"/>                                                                                                                                                    
1137. <action application="export" data="presence_data=x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6"/>                                                                                                                                                                                                              
1138. <action application="bridge" data="{sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381606257565"/>                                                                    
1139. </condition>                                                                                                                                                                                                      
1140. </extension>                                                                                                                                                                                                      
1141. </context>                                                                                                                                                                                                        
1142. </section>                                                                                                                                                                                                        
1143. </document>                                                                                                                                                                                                        
1144. Dialplan: sofia/default/Pab011Med@10.44.44.50 parsing [default->381606257565] continue=false
1145. Dialplan: sofia/default/Pab011Med@10.44.44.50 Regex (PASS) [381606257565] destination_number(0606257565) =~ /0606257565/ break=on-false
1146. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(effective_destination_number=381606257565)
1147. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(bridge_pre_execute_bleg_app=sched_hangup)
1148. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
1149. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(notify_flag=0)
1150. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(notify_credit_limit=5.00000)
1151. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(type=0)
1152. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(callstart=2024-04-17 12:00:41)
1153. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(hangup_after_bridge=true)
1154. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(continue_on_fail=TRUE)
1155. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(account_id=66)
1156. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(parent_id=0)
1157. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(entity_id=0)
1158. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(call_processed=internal)
1159. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(call_direction=outbound)
1160. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(accountname=default)
1161. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action export(t38_passthru=true)
1162. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(fax_enable_t38=true)
1163. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(fax_enable_t38_request=true)
1164. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(origination_rates=ID:687|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:4|MARKUP:0|CI:164|ACCID:66)                                                                                                                                                                            
1165. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(original_caller_id_name=Maja B)
1166. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(original_caller_id_number=381114419417)
1167. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(effective_caller_id_name=Maja B)
1168. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(effective_caller_id_number=381114419417)
1169. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(calltype=STANDARD)
1170. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                                
1171. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(trunk_id=4)
1172. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(provider_id=8)
1173. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(rate_flag=0)
1174. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(daily_call_credit_limit=0.00000)
1175. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(monthly_call_credit_limit=0.00000)
1176. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(daily_call_used_limit=0.00000)
1177. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(monthly_call_used_limit=0.00000)
1178. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(quality_trunk_seq=4)
1179. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(force_trunk_flag=0)
1180. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action export(presence_data=trunk_id=4)
1181. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action set(intcall=0)
1182. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action export(presence_id=381606257565)
1183. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action export(presence_data=x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6)                                                                                                                                                                                        
1184. Dialplan: sofia/default/Pab011Med@10.44.44.50 Action bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381606257565)
1185. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_core_state_machine.c:281 (sofia/default/Pab011Med@10.44.44.50) State Change CS_ROUTING -> CS_EXECUTE
1186. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/Pab011Med@10.44.44.50) State ROUTING going to sleep
1187. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/Pab011Med@10.44.44.50) Running State Change CS_EXECUTE (Cur 33 Tot 248048)
1188. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_core_state_machine.c:647 (sofia/default/Pab011Med@10.44.44.50) State EXECUTE
1189. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_sofia.c:213 sofia/default/Pab011Med@10.44.44.50 SOFIA EXECUTE
1190. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_core_state_machine.c:323 sofia/default/Pab011Med@10.44.44.50 Standard EXECUTE
1191. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(effective_destination_number=381606257565)
1192. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [effective_destination_number]=[381606257565]
1193. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(bridge_pre_execute_bleg_app=sched_hangup)
1194. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [bridge_pre_execute_bleg_app]=[sched_hangup]
1195. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(bridge_pre_execute_bleg_data=+6000 normal_clearing)
1196. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [bridge_pre_execute_bleg_data]=[+6000 normal_clearing]
1197. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(notify_flag=0)
1198. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [notify_flag]=[0]
1199. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(notify_credit_limit=5.00000)
1200. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [notify_credit_limit]=[5.00000]
1201. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(type=0)
1202. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [type]=[0]
1203. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(callstart=2024-04-17 12:00:41)
1204. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [callstart]=[2024-04-17 12:00:41]
1205. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(hangup_after_bridge=true)
1206. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [hangup_after_bridge]=[true]
1207. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(continue_on_fail=TRUE)
1208. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [continue_on_fail]=[TRUE]
1209. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(account_id=66)
1210. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [account_id]=[66]
1211. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(parent_id=0)
1212. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [parent_id]=[0]
1213. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(entity_id=0)
1214. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [entity_id]=[0]
1215. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(call_processed=internal)
1216. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [call_processed]=[internal]
1217. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(call_direction=outbound)
1218. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [call_direction]=[outbound]
1219. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(accountname=default)
1220. 2024-04-17 14:00:41.437505 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [accountname]=[default]
1221. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 export(t38_passthru=true)
1222. 2024-04-17 14:00:41.437505 94.87% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [t38_passthru]=[true]
1223. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(fax_enable_t38=true)
1224. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [fax_enable_t38]=[true]
1225. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(fax_enable_t38_request=true)
1226. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [fax_enable_t38_request]=[true]
1227. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(origination_rates=ID:687|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:4|MARKUP:0|CI:164|ACCID:66)                                                                                                                                                                            
1228. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [origination_rates]=[ID:687|CODE:^3816.*|DESTINATION:Serbia-Mobile|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|CT:|COST:0.0400000000|INC:60|INITIALBLOCK:60|RATEGROUP:4|MARKUP:0|CI:164|ACCID:66]                                                                                                                              
1229. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(original_caller_id_name=Maja B)
1230. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [original_caller_id_name]=[Maja B]
1231. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(original_caller_id_number=381114419417)
1232. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [original_caller_id_number]=[381114419417]
1233. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(effective_caller_id_name=Maja B)
1234. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [effective_caller_id_name]=[Maja B]
1235. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(effective_caller_id_number=381114419417)
1236. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [effective_caller_id_number]=[381114419417]
1237. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(calltype=STANDARD)
1238. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [calltype]=[STANDARD]
1239. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(termination_rates=ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8)                                                                                                                                                                                                
1240. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [termination_rates]=[ID:15926|CODE:^3816.*|DESTINATION:Serbia-Mobilni|CONNECTIONCOST:0.0000000000|INCLUDEDSECONDS:0|COST:0.0191000000|INC:1|INITIALBLOCK:60|TRUNK:4|PROVIDER:8]                                                                                                                                                  
1241. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(trunk_id=4)
1242. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [trunk_id]=[4]
1243. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(provider_id=8)
1244. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [provider_id]=[8]
1245. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(rate_flag=0)
1246. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [rate_flag]=[0]
1247. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(daily_call_credit_limit=0.00000)
1248. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [daily_call_credit_limit]=[0.00000]
1249. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(monthly_call_credit_limit=0.00000)
1250. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [monthly_call_credit_limit]=[0.00000]
1251. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(daily_call_used_limit=0.00000)
1252. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [daily_call_used_limit]=[0.00000]
1253. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(monthly_call_used_limit=0.00000)
1254. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [monthly_call_used_limit]=[0.00000]
1255. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(quality_trunk_seq=4)
1256. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [quality_trunk_seq]=[4]
1257. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(force_trunk_flag=0)
1258. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [force_trunk_flag]=[0]
1259. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 export(presence_data=trunk_id=4)
1260. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[trunk_id=4]
1261. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 set(intcall=0)
1262. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_dptools.c:1671 SET sofia/default/Pab011Med@10.44.44.50 [intcall]=[0]
1263. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 export(presence_id=381606257565)
1264. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_id]=[381606257565]
1265. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 export(presence_data=x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6)                                                                                                                                                                                      
1266. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1334 EXPORT (export_vars) [presence_data]=[x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6]                                                                                                                                                            
1267. EXECUTE [depth=0] sofia/default/Pab011Med@10.44.44.50 bridge({sip_cid_type=none}[leg_timeout=30,absolute_codec_string=^^:PCMA:PCMU]sofia/gateway/Asterisk/381606257565)
1268. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1288 sofia/default/Pab011Med@10.44.44.50 EXPORTING[export_vars] [t38_passthru]=[true] to event
1269. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1288 sofia/default/Pab011Med@10.44.44.50 EXPORTING[export_vars] [presence_data]=[x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6] to event                                                                                                            
1270. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1288 sofia/default/Pab011Med@10.44.44.50 EXPORTING[export_vars] [presence_id]=[381606257565] to event
1271. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_channel.c:1288 sofia/default/Pab011Med@10.44.44.50 EXPORTING[export_vars] [presence_data]=[x|||PublicMedia(9999296736)|||^3816.* // Serbia-Mobile // 0.0400000000 |||TELENOR RS // ^3816.* // Serbia-Mobilni // 0.0191000000 // trunk_id=4|||STD|||82.214.92.6] to event                                                                                                            
1272. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_ivr_originate.c:2297 Parsing global variables
1273. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_ivr_originate.c:2859 Parsing session specific variables
1274. 2024-04-17 14:00:41.457513 94.87% [NOTICE] switch_channel.c:1142 New Channel sofia/default/381606257565 [45139949-7b83-4b46-bfcf-70e270310f6e]
1275. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_sofia.c:5110 (sofia/default/381606257565) State Change CS_NEW -> CS_INIT
1276. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_ivr_originate.c:3167 sofia/default/381606257565 Setting leg timeout to 30
1277. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381606257565) Running State Change CS_INIT (Cur 34 Tot 248049)
1278. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381606257565) State INIT
1279. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_sofia.c:97 sofia/default/381606257565 SOFIA INIT
1280. 2024-04-17 14:00:41.457513 94.87% [INFO] sofia_glue.c:1659 sofia/default/381606257565 sending invite call-id: (null)
1281. 2024-04-17 14:00:41.457513 94.87% [DEBUG] sofia_glue.c:1662 sofia/default/381606257565 sending invite version: 1.10.10-release  64bit
1282. Local SDP:                                                                                                                                                                                                        
1283. v=0                                                                                                                                                                                                                
1284. o=FreeSWITCH 1713330983 1713330984 IN IP4 82.214.92.4                                                                                                                                                              
1285. s=FreeSWITCH                                                                                                                                                                                                      
1286. c=IN IP4 82.214.92.4                                                                                                                                                                                              
1287. t=0 0                                                                                                                                                                                                              
1288. m=audio 24258 RTP/AVP 8 0 101                                                                                                                                                                                      
1289. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1290. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1291. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1292. a=fmtp:101 0-15                                                                                                                                                                                                    
1293. a=ptime:20                                                                                                                                                                                                        
1294. a=sendrecv                                                                                                                                                                                                        
1295.  
1296. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:40 sofia/default/381606257565 Standard INIT
1297. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:48 (sofia/default/381606257565) State Change CS_INIT -> CS_ROUTING
1298. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:624 (sofia/default/381606257565) State INIT going to sleep
1299. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381606257565) Running State Change CS_ROUTING (Cur 34 Tot 248049)
1300. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381606257565) State ROUTING
1301. 2024-04-17 14:00:41.457513 94.87% [DEBUG] mod_sofia.c:158 sofia/default/381606257565 SOFIA ROUTING
1302. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_ivr_originate.c:67 (sofia/default/381606257565) State Change CS_ROUTING -> CS_CONSUME_MEDIA
1303. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:640 (sofia/default/381606257565) State ROUTING going to sleep
1304. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381606257565) Running State Change CS_CONSUME_MEDIA (Cur 34 Tot 248049)
1305. 2024-04-17 14:00:41.457513 94.87% [DEBUG] sofia.c:7493 Channel sofia/default/381606257565 entering state [calling][0]
1306. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:659 (sofia/default/381606257565) State CONSUME_MEDIA
1307. 2024-04-17 14:00:41.457513 94.87% [DEBUG] switch_core_state_machine.c:659 (sofia/default/381606257565) State CONSUME_MEDIA going to sleep
1308. 2024-04-17 14:00:41.557504 94.87% [DEBUG] sofia.c:7493 Channel sofia/default/38166402602 entering state [proceeding][183]
1309. 2024-04-17 14:00:41.557504 94.87% [DEBUG] sofia.c:7503 Remote SDP:
1310. v=0                                                                                                                                                                                                                
1311. o=- 1713330251 1713330254 IN IP4 82.214.92.11                                                                                                                                                                      
1312. s=Asterisk                                                                                                                                                                                                        
1313. c=IN IP4 82.214.92.11                                                                                                                                                                                              
1314. t=0 0                                                                                                                                                                                                              
1315. m=audio 10934 RTP/AVP 8 0 101                                                                                                                                                                                      
1316. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1317. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1318. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1319. a=fmtp:101 0-16                                                                                                                                                                                                    
1320. a=ptime:20                                                                                                                                                                                                        
1321. a=maxptime:140                                                                                                                                                                                                    
1322.  
1323. 2024-04-17 14:00:41.557504 94.87% [NOTICE] sofia.c:7506 Pre-Answer sofia/default/38166402602!
1324. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_channel.c:3585 (sofia/default/38166402602) Callstate Change DOWN -> EARLY
1325. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1326. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1327. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1328. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1329. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1330. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
1331. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
1332. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/38166402602 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
1333. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_codec.c:111 sofia/default/38166402602 Original read codec set to PCMA:8
1334. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
1335. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:5856 sofia/default/38166402602 Set 2833 dtmf send payload to 101 recv payload to 101
1336. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/38166402602] 10.44.44.50 port 24988 -> 82.214.92.11 port 10934 codec: 8 ms: 20
1337. 2024-04-17 14:00:41.557504 94.87% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
1338. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:8972 sofia/default/38166402602 Set 2833 dtmf send payload to 101
1339. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:8979 sofia/default/38166402602 Set 2833 dtmf receive payload to 101
1340. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:9002 sofia/default/38166402602 Set rtp dtmf delay to 40
1341. 2024-04-17 14:00:41.577524 94.87% [INFO] switch_ivr_originate.c:3858 Sending early media
1342. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/381114419368@10.44.44.50] 10.44.44.50 port 30664 -> 10.66.66.50 port 54020 codec: 0 ms: 20
1343. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
1344. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:8972 sofia/default/381114419368@10.44.44.50 Set 2833 dtmf send payload to 100
1345. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:8979 sofia/default/381114419368@10.44.44.50 Set 2833 dtmf receive payload to 100
1346. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_media.c:9002 sofia/default/381114419368@10.44.44.50 Set rtp dtmf delay to 40
1347. 2024-04-17 14:00:41.577524 94.87% [DEBUG] mod_sofia.c:2607 Ring SDP:
1348. v=0                                                                                                                                                                                                                
1349. o=FreeSWITCH 1713324577 1713324578 IN IP4 82.214.92.4                                                                                                                                                              
1350. s=FreeSWITCH                                                                                                                                                                                                      
1351. c=IN IP4 82.214.92.4                                                                                                                                                                                              
1352. t=0 0                                                                                                                                                                                                              
1353. m=audio 30664 RTP/AVP 0 100                                                                                                                                                                                        
1354. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1355. a=rtpmap:100 telephone-event/8000                                                                                                                                                                                  
1356. a=fmtp:100 0-15                                                                                                                                                                                                    
1357. a=ptime:20                                                                                                                                                                                                        
1358. a=sendrecv                                                                                                                                                                                                        
1359.  
1360. 2024-04-17 14:00:41.577524 94.87% [NOTICE] mod_sofia.c:2610 Pre-Answer sofia/default/381114419368@10.44.44.50!
1361. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_channel.c:3585 (sofia/default/381114419368@10.44.44.50) Callstate Change RINGING -> EARLY
1362. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/38166402602] Peer UUID: 3c2c4a07-23ad-49b7-942b-b1c1b70198ab
1363. 2024-04-17 14:00:41.577524 94.87% [DEBUG] sofia.c:7493 Channel sofia/default/381114419368@10.44.44.50 entering state [early][183]
1364. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/38166402602) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
1365. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38166402602) Running State Change CS_EXCHANGE_MEDIA (Cur 34 Tot 248049)
1366. 2024-04-17 14:00:41.577524 94.87% [DEBUG] switch_core_state_machine.c:650 (sofia/default/38166402602) State EXCHANGE_MEDIA
1367. 2024-04-17 14:00:41.577524 94.87% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
1368. 2024-04-17 14:00:41.757506 94.87% [DEBUG] switch_ivr_bridge.c:654 sofia/default/38166402602 Bridge execute app sched_hangup(+6000 normal_clearing)
1369. 2024-04-17 14:00:41.777507 94.87% [DEBUG] switch_ivr.c:632 sofia/default/38166402602 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
1370. EXECUTE [depth=0] sofia/default/38166402602 sched_hangup(+6000 normal_clearing)
1371. 2024-04-17 14:00:41.777507 94.87% [DEBUG] switch_scheduler.c:263 Added task 110337 switch_ivr_schedule_hangup (3c2c4a07-23ad-49b7-942b-b1c1b70198ab) to run at 1713361241
1372. 2024-04-17 14:00:41.897504 94.87% [DEBUG] sofia.c:7493 Channel sofia/default/38166402602 entering state [proceeding][183]
1373. 2024-04-17 14:00:41.897504 94.87% [DEBUG] sofia.c:7500 Duplicate SDP
1374. v=0                                                                                                                                                                                                                
1375. o=- 1713330251 1713330254 IN IP4 82.214.92.11                                                                                                                                                                      
1376. s=Asterisk                                                                                                                                                                                                        
1377. c=IN IP4 82.214.92.11                                                                                                                                                                                              
1378. t=0 0                                                                                                                                                                                                              
1379. m=audio 10934 RTP/AVP 8 0 101                                                                                                                                                                                      
1380. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1381. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1382. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1383. a=fmtp:101 0-16                                                                                                                                                                                                    
1384. a=ptime:20                                                                                                                                                                                                        
1385. a=maxptime:140                                                                                                                                                                                                    
1386.  
1387. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1388. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1389. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1390. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1391. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1392. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
1393. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
1394. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
1395. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:5856 sofia/default/38166402602 Set 2833 dtmf send payload to 101 recv payload to 101
1396. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/38166402602.
1397. 2024-04-17 14:00:41.897504 94.87% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
1398. 2024-04-17 14:00:43.237505 94.83% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
1399. 2024-04-17 14:00:43.257513 94.83% [DEBUG] sofia.c:7493 Channel sofia/default/381606257565 entering state [proceeding][183]
1400. 2024-04-17 14:00:43.257513 94.83% [DEBUG] sofia.c:7503 Remote SDP:
1401. v=0                                                                                                                                                                                                                
1402. o=- 1713330983 1713330986 IN IP4 82.214.92.11                                                                                                                                                                      
1403. s=Asterisk                                                                                                                                                                                                        
1404. c=IN IP4 82.214.92.11                                                                                                                                                                                              
1405. t=0 0                                                                                                                                                                                                              
1406. m=audio 14590 RTP/AVP 8 0 101                                                                                                                                                                                      
1407. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1408. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1409. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1410. a=fmtp:101 0-16                                                                                                                                                                                                    
1411. a=ptime:20                                                                                                                                                                                                        
1412. a=maxptime:140                                                                                                                                                                                                    
1413.  
1414. 2024-04-17 14:00:43.257513 94.83% [NOTICE] sofia.c:7506 Pre-Answer sofia/default/381606257565!
1415. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_channel.c:3585 (sofia/default/381606257565) Callstate Change DOWN -> EARLY
1416. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1417. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1418. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1419. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1420. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1421. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
1422. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
1423. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/381606257565 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
1424. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_codec.c:111 sofia/default/381606257565 Original read codec set to PCMA:8
1425. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
1426. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:5856 sofia/default/381606257565 Set 2833 dtmf send payload to 101 recv payload to 101
1427. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/381606257565] 10.44.44.50 port 24258 -> 82.214.92.11 port 14590 codec: 8 ms: 20
1428. 2024-04-17 14:00:43.257513 94.83% [INFO] switch_ivr_originate.c:3858 Sending early media
1429. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
1430. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_core_media.c:8659 AUDIO RTP [sofia/default/Pab011Med@10.44.44.50] 10.44.44.50 port 17590 -> 10.66.66.50 port 53880 codec: 8 ms: 20
1431. 2024-04-17 14:00:43.257513 94.83% [DEBUG] switch_rtp.c:4373 Starting timer [soft] 160 bytes per 20ms
1432. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:8972 sofia/default/381606257565 Set 2833 dtmf send payload to 101
1433. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:8979 sofia/default/381606257565 Set 2833 dtmf receive payload to 101
1434. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:9002 sofia/default/381606257565 Set rtp dtmf delay to 40
1435. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:8972 sofia/default/Pab011Med@10.44.44.50 Set 2833 dtmf send payload to 101
1436. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:8979 sofia/default/Pab011Med@10.44.44.50 Set 2833 dtmf receive payload to 101
1437. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_media.c:9002 sofia/default/Pab011Med@10.44.44.50 Set rtp dtmf delay to 40
1438. 2024-04-17 14:00:43.277508 94.83% [DEBUG] mod_sofia.c:2607 Ring SDP:
1439. v=0                                                                                                                                                                                                                
1440. o=FreeSWITCH 1713337653 1713337654 IN IP4 82.214.92.4                                                                                                                                                              
1441. s=FreeSWITCH                                                                                                                                                                                                      
1442. c=IN IP4 82.214.92.4                                                                                                                                                                                              
1443. t=0 0                                                                                                                                                                                                              
1444. m=audio 17590 RTP/AVP 8 101                                                                                                                                                                                        
1445. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1446. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1447. a=fmtp:101 0-15                                                                                                                                                                                                    
1448. a=ptime:20                                                                                                                                                                                                        
1449. a=sendrecv                                                                                                                                                                                                        
1450.  
1451. 2024-04-17 14:00:43.277508 94.83% [NOTICE] mod_sofia.c:2610 Pre-Answer sofia/default/Pab011Med@10.44.44.50!
1452. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_channel.c:3585 (sofia/default/Pab011Med@10.44.44.50) Callstate Change RINGING -> EARLY
1453. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_ivr_originate.c:3909 Originate Resulted in Success: [sofia/default/381606257565] Peer UUID: 45139949-7b83-4b46-bfcf-70e270310f6e
1454. 2024-04-17 14:00:43.277508 94.83% [DEBUG] sofia.c:7493 Channel sofia/default/Pab011Med@10.44.44.50 entering state [early][183]
1455. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_ivr_bridge.c:1791 (sofia/default/381606257565) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
1456. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381606257565) Running State Change CS_EXCHANGE_MEDIA (Cur 34 Tot 248049)
1457. 2024-04-17 14:00:43.277508 94.83% [DEBUG] switch_core_state_machine.c:650 (sofia/default/381606257565) State EXCHANGE_MEDIA
1458. 2024-04-17 14:00:43.277508 94.83% [DEBUG] mod_sofia.c:672 SOFIA EXCHANGE_MEDIA
1459. 2024-04-17 14:00:43.297506 94.83% [DEBUG] switch_core_media.c:3190 alternate payload received (received 0, expecting 8)
1460. 2024-04-17 14:00:43.297506 94.83% [WARNING] switch_core_media.c:3201 Changing current codec to PCMU (payload type 0).
1461. 2024-04-17 14:00:43.317527 94.73% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMA@20ms@8000hz to PCMU@20ms@8000hz
1462. 2024-04-17 14:00:43.337518 94.73% [DEBUG] sofia.c:7493 Channel sofia/default/381606257565 entering state [proceeding][183]
1463. 2024-04-17 14:00:43.337518 94.73% [DEBUG] sofia.c:7500 Duplicate SDP
1464. v=0                                                                                                                                                                                                                
1465. o=- 1713330983 1713330986 IN IP4 82.214.92.11                                                                                                                                                                      
1466. s=Asterisk                                                                                                                                                                                                        
1467. c=IN IP4 82.214.92.11                                                                                                                                                                                              
1468. t=0 0                                                                                                                                                                                                              
1469. m=audio 14590 RTP/AVP 8 0 101                                                                                                                                                                                      
1470. a=rtpmap:8 PCMA/8000                                                                                                                                                                                              
1471. a=rtpmap:0 PCMU/8000                                                                                                                                                                                              
1472. a=rtpmap:101 telephone-event/8000                                                                                                                                                                                  
1473. a=fmtp:101 0-16                                                                                                                                                                                                    
1474. a=ptime:20                                                                                                                                                                                                        
1475. a=maxptime:140                                                                                                                                                                                                    
1476.  
1477. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1478. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1479. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1480. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1481. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5526 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
1482. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5588 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
1483. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5438 Set telephone-event payload to 101@8000
1484. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5798 Set telephone-event payload to 101@8000
1485. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:5856 sofia/default/381606257565 Set 2833 dtmf send payload to 101 recv payload to 101
1486. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:8641 Audio params are unchanged for sofia/default/381606257565.
1487. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/Pab011Med@10.44.44.50 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
1488. 2024-04-17 14:00:43.337518 94.73% [DEBUG] switch_core_codec.c:124 sofia/default/Pab011Med@10.44.44.50 Original read codec replaced with PCMU:0
1489. 2024-04-17 14:00:43.357505 94.73% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
1490. 2024-04-17 14:00:43.357505 94.73% [DEBUG] switch_core_media.c:3190 alternate payload received (received 0, expecting 8)
1491. 2024-04-17 14:00:43.357505 94.73% [WARNING] switch_core_media.c:3201 Changing current codec to PCMU (payload type 0).
1492. 2024-04-17 14:00:43.377506 94.73% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMA@20ms@8000hz to PCMU@20ms@8000hz
1493. 2024-04-17 14:00:43.397506 94.73% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/381606257565 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
1494. 2024-04-17 14:00:43.397506 94.73% [DEBUG] switch_core_codec.c:124 sofia/default/381606257565 Original read codec replaced with PCMU:0
1495. 2024-04-17 14:00:43.457507 94.73% [DEBUG] switch_ivr_bridge.c:654 sofia/default/381606257565 Bridge execute app sched_hangup(+6000 normal_clearing)
1496. 2024-04-17 14:00:43.477508 94.73% [DEBUG] switch_ivr.c:632 sofia/default/381606257565 Command Execute [depth=0] sched_hangup(+6000 normal_clearing)
1497. EXECUTE [depth=0] sofia/default/381606257565 sched_hangup(+6000 normal_clearing)
1498. 2024-04-17 14:00:43.477508 94.73% [DEBUG] switch_scheduler.c:263 Added task 110338 switch_ivr_schedule_hangup (45139949-7b83-4b46-bfcf-70e270310f6e) to run at 1713361243
1499. 2024-04-17 14:00:43.637539 94.73% [DEBUG] switch_rtp.c:7503 Correct audio ip/port confirmed.
1500. 2024-04-17 14:00:43.637539 94.73% [DEBUG] switch_core_media.c:3190 alternate payload received (received 8, expecting 0)
1501. 2024-04-17 14:00:43.637539 94.73% [WARNING] switch_core_media.c:3201 Changing current codec to PCMA (payload type 8).
1502. 2024-04-17 14:00:43.657516 94.73% [DEBUG] switch_core_media.c:3190 alternate payload received (received 8, expecting 0)
1503. 2024-04-17 14:00:43.657516 94.73% [WARNING] switch_core_media.c:3201 Changing current codec to PCMA (payload type 8).
1504. 2024-04-17 14:00:43.677517 94.73% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMU@20ms@8000hz to PCMA@20ms@8000hz
1505. 2024-04-17 14:00:43.677517 94.73% [DEBUG] switch_core_media.c:3635 Changing Codec from PCMU@20ms@8000hz to PCMA@20ms@8000hz
1506. 2024-04-17 14:00:43.697517 94.73% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/Pab011Med@10.44.44.50 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
1507. 2024-04-17 14:00:43.697517 94.73% [DEBUG] switch_core_codec.c:124 sofia/default/Pab011Med@10.44.44.50 Original read codec replaced with PCMA:8
1508. 2024-04-17 14:00:43.697517 94.73% [DEBUG] switch_core_media.c:3734 Set Codec sofia/default/381606257565 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
1509. 2024-04-17 14:00:43.697517 94.73% [DEBUG] switch_core_codec.c:124 sofia/default/381606257565 Original read codec replaced with PCMA:8
1510. 2024-04-17 14:00:44.757518 94.73% [NOTICE] sofia.c:1065 Hangup sofia/default/381114419368@10.44.44.50 [CS_EXECUTE] [NORMAL_CLEARING]
1511. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/381114419368@10.44.44.50]
1512. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_ivr_bridge.c:819 sofia/default/381114419368@10.44.44.50 ending bridge by request from write function
1513. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/38162262500]
1514. 2024-04-17 14:00:44.757518 94.73% [NOTICE] switch_ivr_bridge.c:1027 Hangup sofia/default/38162262500 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1515. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_core_state_machine.c:650 (sofia/default/38162262500) State EXCHANGE_MEDIA going to sleep
1516. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38162262500) Running State Change CS_HANGUP (Cur 34 Tot 248049)
1517. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/38162262500) Callstate Change ACTIVE -> HANGUP
1518. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/38162262500) State HANGUP
1519. 2024-04-17 14:00:44.757518 94.73% [DEBUG] mod_sofia.c:463 sofia/default/38162262500 Overriding SIP cause 480 with 200 from the other leg
1520. 2024-04-17 14:00:44.757518 94.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/38162262500 hanging up, cause: NORMAL_CLEARING
1521. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/38162262500 skip receive message [UNBRIDGE] (channel is hungup already)
1522. 2024-04-17 14:00:44.757518 94.73% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/381114419368@10.44.44.50 skip receive message [UNBRIDGE] (channel is hungup already)
1523. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_session.c:2979 sofia/default/381114419368@10.44.44.50 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1524. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:647 (sofia/default/381114419368@10.44.44.50) State EXECUTE going to sleep
1525. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_HANGUP (Cur 34 Tot 248049)
1526. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/381114419368@10.44.44.50) Callstate Change ACTIVE -> HANGUP
1527. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/381114419368@10.44.44.50) State HANGUP
1528. 2024-04-17 14:00:44.777525 94.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/381114419368@10.44.44.50 hanging up, cause: NORMAL_CLEARING
1529. 2024-04-17 14:00:44.777525 94.73% [DEBUG] mod_sofia.c:523 Sending BYE to sofia/default/38162262500
1530. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/38162262500 Standard HANGUP, cause: NORMAL_CLEARING
1531. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/38162262500) State HANGUP going to sleep
1532. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/38162262500) State Change CS_HANGUP -> CS_REPORTING
1533. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/38162262500) Running State Change CS_REPORTING (Cur 34 Tot 248049)
1534. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/38162262500) State REPORTING
1535. 2024-04-17 14:00:44.777525 94.73% [INFO] mod_json_cdr.c:272 Process [8537953a-559b-4adf-8c91-0f924d614fd1.cdr.json]
1536. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/381114419368@10.44.44.50 Standard HANGUP, cause: NORMAL_CLEARING
1537. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/381114419368@10.44.44.50) State HANGUP going to sleep
1538. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/381114419368@10.44.44.50) State Change CS_HANGUP -> CS_REPORTING
1539. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381114419368@10.44.44.50) Running State Change CS_REPORTING (Cur 34 Tot 248049)
1540. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/381114419368@10.44.44.50) State REPORTING
1541. 2024-04-17 14:00:44.777525 94.73% [INFO] mod_json_cdr.c:272 Process [ec48f55a-d5cf-4960-8887-d44c1dd04845.cdr.json]
1542. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/38162262500 Standard REPORTING, cause: NORMAL_CLEARING
1543. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/38162262500) State REPORTING going to sleep
1544. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/38162262500) State Change CS_REPORTING -> CS_DESTROY
1545. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_session.c:1744 Session 247938 (sofia/default/38162262500) Locked, Waiting on external entities
1546. 2024-04-17 14:00:44.777525 94.73% [NOTICE] switch_core_session.c:1762 Session 247938 (sofia/default/38162262500) Ended
1547. 2024-04-17 14:00:44.777525 94.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/38162262500 [CS_DESTROY]
1548. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/38162262500) Running State Change CS_DESTROY (Cur 33 Tot 248049)
1549. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/38162262500) State DESTROY
1550. 2024-04-17 14:00:44.777525 94.73% [DEBUG] mod_sofia.c:380 sofia/default/38162262500 SOFIA DESTROY
1551. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/38162262500 Standard DESTROY
1552. 2024-04-17 14:00:44.777525 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/38162262500) State DESTROY going to sleep
1553. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/381114419368@10.44.44.50 Standard REPORTING, cause: NORMAL_CLEARING
1554. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/381114419368@10.44.44.50) State REPORTING going to sleep
1555. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/381114419368@10.44.44.50) State Change CS_REPORTING -> CS_DESTROY
1556. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_session.c:1744 Session 247937 (sofia/default/381114419368@10.44.44.50) Locked, Waiting on external entities
1557. 2024-04-17 14:00:44.797514 94.73% [NOTICE] switch_core_session.c:1762 Session 247937 (sofia/default/381114419368@10.44.44.50) Ended
1558. 2024-04-17 14:00:44.797514 94.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/381114419368@10.44.44.50 [CS_DESTROY]
1559. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/381114419368@10.44.44.50) Running State Change CS_DESTROY (Cur 32 Tot 248049)
1560. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/381114419368@10.44.44.50) State DESTROY
1561. 2024-04-17 14:00:44.797514 94.73% [DEBUG] mod_sofia.c:380 sofia/default/381114419368@10.44.44.50 SOFIA DESTROY
1562. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/381114419368@10.44.44.50 Standard DESTROY
1563. 2024-04-17 14:00:44.797514 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/381114419368@10.44.44.50) State DESTROY going to sleep
1564. 2024-04-17 14:00:44.977505 94.73% [DEBUG] switch_scheduler.c:147 Deleting task 110287 switch_ivr_schedule_hangup (8537953a-559b-4adf-8c91-0f924d614fd1)
1565. 2024-04-17 14:00:45.037504 94.73% [DEBUG] sofia.c:7493 Channel sofia/default/sky111v6@tdsl.skytel.rs entering state [terminated][487]
1566. 2024-04-17 14:00:45.037504 94.73% [NOTICE] sofia.c:8735 Hangup sofia/default/sky111v6@tdsl.skytel.rs [CS_EXECUTE] [ORIGINATOR_CANCEL]
1567. 2024-04-17 14:00:45.037504 94.73% [DEBUG] switch_ivr_bridge.c:825 sofia/default/sky111v6@tdsl.skytel.rs ending bridge by request from read function
1568. 2024-04-17 14:00:45.037504 94.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/sky111v6@tdsl.skytel.rs]
1569. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_ivr_bridge.c:819 sofia/default/sky111v6@tdsl.skytel.rs ending bridge by request from write function
1570. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_ivr_bridge.c:911 BRIDGE THREAD DONE [sofia/default/381653552255]
1571. 2024-04-17 14:00:45.057503 94.73% [NOTICE] switch_ivr_bridge.c:1023 Hangup sofia/default/381653552255 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
1572. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:650 (sofia/default/381653552255) State EXCHANGE_MEDIA going to sleep
1573. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_HANGUP (Cur 32 Tot 248049)
1574. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/381653552255) Callstate Change EARLY -> HANGUP
1575. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/381653552255) State HANGUP
1576. 2024-04-17 14:00:45.057503 94.73% [DEBUG] mod_sofia.c:463 sofia/default/381653552255 Overriding SIP cause 487 with 487 from the other leg
1577. 2024-04-17 14:00:45.057503 94.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/381653552255 hanging up, cause: ORIGINATOR_CANCEL
1578. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_ivr_bridge.c:1890 sofia/default/381653552255 skip receive message [UNBRIDGE] (channel is hungup already)
1579. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_ivr_bridge.c:1893 sofia/default/sky111v6@tdsl.skytel.rs skip receive message [UNBRIDGE] (channel is hungup already)
1580. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_session.c:2979 sofia/default/sky111v6@tdsl.skytel.rs skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1581. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:647 (sofia/default/sky111v6@tdsl.skytel.rs) State EXECUTE going to sleep
1582. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_HANGUP (Cur 32 Tot 248049)
1583. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:844 (sofia/default/sky111v6@tdsl.skytel.rs) Callstate Change EARLY -> HANGUP
1584. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/sky111v6@tdsl.skytel.rs) State HANGUP
1585. 2024-04-17 14:00:45.057503 94.73% [DEBUG] mod_sofia.c:469 Channel sofia/default/sky111v6@tdsl.skytel.rs hanging up, cause: ORIGINATOR_CANCEL
1586. 2024-04-17 14:00:45.057503 94.73% [DEBUG] mod_sofia.c:537 Sending CANCEL to sofia/default/381653552255
1587. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/381653552255 Standard HANGUP, cause: ORIGINATOR_CANCEL
1588. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/381653552255) State HANGUP going to sleep
1589. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/381653552255) State Change CS_HANGUP -> CS_REPORTING
1590. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/381653552255) Running State Change CS_REPORTING (Cur 32 Tot 248049)
1591. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/381653552255) State REPORTING
1592. 2024-04-17 14:00:45.057503 94.73% [INFO] mod_json_cdr.c:272 Process [23ac4750-7398-41ac-b5db-770275cb59db.cdr.json]
1593. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:59 sofia/default/sky111v6@tdsl.skytel.rs Standard HANGUP, cause: ORIGINATOR_CANCEL
1594. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:846 (sofia/default/sky111v6@tdsl.skytel.rs) State HANGUP going to sleep
1595. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:616 (sofia/default/sky111v6@tdsl.skytel.rs) State Change CS_HANGUP -> CS_REPORTING
1596. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:581 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_REPORTING (Cur 32 Tot 248049)
1597. 2024-04-17 14:00:45.057503 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/sky111v6@tdsl.skytel.rs) State REPORTING
1598. 2024-04-17 14:00:45.057503 94.73% [INFO] mod_json_cdr.c:272 Process [c62300b1-02ef-4df8-b418-f3bb95236bbb.cdr.json]
1599. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/381653552255 Standard REPORTING, cause: ORIGINATOR_CANCEL
1600. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/381653552255) State REPORTING going to sleep
1601. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/381653552255) State Change CS_REPORTING -> CS_DESTROY
1602. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_session.c:1744 Session 248045 (sofia/default/381653552255) Locked, Waiting on external entities
1603. 2024-04-17 14:00:45.077517 94.73% [NOTICE] switch_core_session.c:1762 Session 248045 (sofia/default/381653552255) Ended
1604. 2024-04-17 14:00:45.077517 94.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/381653552255 [CS_DESTROY]
1605. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/381653552255) Running State Change CS_DESTROY (Cur 31 Tot 248049)
1606. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/381653552255) State DESTROY
1607. 2024-04-17 14:00:45.077517 94.73% [DEBUG] mod_sofia.c:380 sofia/default/381653552255 SOFIA DESTROY
1608. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/381653552255 Standard DESTROY
1609. 2024-04-17 14:00:45.077517 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/381653552255) State DESTROY going to sleep
1610. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:168 sofia/default/sky111v6@tdsl.skytel.rs Standard REPORTING, cause: ORIGINATOR_CANCEL
1611. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:932 (sofia/default/sky111v6@tdsl.skytel.rs) State REPORTING going to sleep
1612. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:607 (sofia/default/sky111v6@tdsl.skytel.rs) State Change CS_REPORTING -> CS_DESTROY
1613. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_session.c:1744 Session 248044 (sofia/default/sky111v6@tdsl.skytel.rs) Locked, Waiting on external entities
1614. 2024-04-17 14:00:45.097568 94.73% [NOTICE] switch_core_session.c:1762 Session 248044 (sofia/default/sky111v6@tdsl.skytel.rs) Ended
1615. 2024-04-17 14:00:45.097568 94.73% [NOTICE] switch_core_session.c:1766 Close Channel sofia/default/sky111v6@tdsl.skytel.rs [CS_DESTROY]
1616. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:735 (sofia/default/sky111v6@tdsl.skytel.rs) Running State Change CS_DESTROY (Cur 30 Tot 248049)
1617. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/sky111v6@tdsl.skytel.rs) State DESTROY
1618. 2024-04-17 14:00:45.097568 94.73% [DEBUG] mod_sofia.c:380 sofia/default/sky111v6@tdsl.skytel.rs SOFIA DESTROY
1619. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:175 sofia/default/sky111v6@tdsl.skytel.rs Standard DESTROY
1620. 2024-04-17 14:00:45.097568 94.73% [DEBUG] switch_core_state_machine.c:745 (sofia/default/sky111v6@tdsl.skytel.rs) State DESTROY going to sleep
1621. 2024-04-17 14:00:45.477527 94.73% [DEBUG] switch_scheduler.c:147 Deleting task 110336 switch_ivr_schedule_hangup (23ac4750-7398-41ac-b5db-770275cb59db)