021-12-01 11:32:09.513557 99.07% [ERR] switch_stun.c:900 STUN Failed! [Timeout] 2021-12-01 11:32:09.513557 99.07% [ERR] switch_xml.c:175 stun-set failed. 2021-12-01 11:32:13.973577 99.03% [DEBUG] sofia_reg.c:2665 Changing expire time to 86 by request of proxy sip:voips.modulus.gr:5060 2021-12-01 11:32:14.533564 99.03% [ERR] switch_stun.c:900 STUN Failed! [Timeout] 2021-12-01 11:32:14.533564 99.03% [ERR] switch_xml.c:175 stun-set failed. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1115 Write lock interface 'sofia' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1123 Deleting Endpoint 'sofia' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1115 Write lock interface 'rtp' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1123 Deleting Endpoint 'rtp' 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1228 Deleting Application 'sofia_sla' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1230 Write lock interface 'sofia_sla' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1228 Deleting Application 'sofia_stir_shaken_vs' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1230 Write lock interface 'sofia_stir_shaken_vs' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_gateway_data' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_gateway_data' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_username_of' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_username_of' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_contact' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_contact' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_count_reg' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_count_reg' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_dig' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_dig' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_presence_data' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia_presence_data' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1315 Deleting API Function 'sofia.status' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1317 Write lock interface 'sofia.status' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1315 Deleting API Function 'sofia.status.info' 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1317 Write lock interface 'sofia.status.info' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1535 Write lock interface 'sip' to wait for existing references. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1544 Deleting Chat interface 'sip' 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1586 Deleting Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1001] 2021-12-01 11:32:14.553556 99.03% [CONSOLE] switch_loadable_module.c:2349 Stopping: mod_sofia 2021-12-01 11:32:14.573566 99.03% [INFO] switch_time.c:1430 Timezone reloaded 1750 definitions 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::notify_refer 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::notify_watched_header 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::unregister 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::profile_start 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::reinvite 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::replaced 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::transferor 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::transferee 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::error 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::intercepted 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_state 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::sip_user_state 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_delete 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::expire 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register_attempt 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register_failure 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::pre_register 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_add 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::bye_response 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_IN 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_OUT 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_PROBE 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:MESSAGE_WAITING 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:ROSTER 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:CONFERENCE_DATA 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:TRAP 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:NOTIFY 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PHONE_FEATURE 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:SEND_MESSAGE 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:SEND_INFO 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:CUSTOM 2021-12-01 11:32:14.573566 99.03% [CONSOLE] sofia_presence.c:1664 Event Thread Ended 2021-12-01 11:32:14.733583 99.03% [NOTICE] sofia.c:3504 Waiting for worker thread 2021-12-01 11:32:14.733583 99.03% [INFO] switch_core_sqldb.c:1876 sofia:default Destroying SQL queue. 2021-12-01 11:32:14.933575 98.97% [INFO] switch_core_sqldb.c:1827 sofia:default Stopping SQL thread. 2021-12-01 11:32:14.933575 98.97% [DEBUG] sofia.c:3576 Write lock default 2021-12-01 11:32:14.933575 98.97% [DEBUG] sofia.c:3589 Write unlock default 2021-12-01 11:32:14.973577 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_sip::ModulusIncoming from hash. 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia_reg.c:137 UN-Registering ModulusIncoming 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway ModulusIncoming 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread 2021-12-01 11:32:14.973577 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_sip Destroying SQL queue. 2021-12-01 11:32:15.133572 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_without_reg::ModulusOutgoing from hash. 2021-12-01 11:32:15.133572 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway ModulusOutgoing 2021-12-01 11:32:15.133572 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread 2021-12-01 11:32:15.133572 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_without_reg Destroying SQL queue. 2021-12-01 11:32:15.173578 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_sip Stopping SQL thread. 2021-12-01 11:32:15.173578 98.97% [DEBUG] sofia.c:3576 Write lock external_sip 2021-12-01 11:32:15.173578 98.97% [DEBUG] sofia.c:3589 Write unlock external_sip 2021-12-01 11:32:15.333576 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_without_reg Stopping SQL thread. 2021-12-01 11:32:15.333576 98.97% [DEBUG] sofia.c:3576 Write lock external_without_reg 2021-12-01 11:32:15.333576 98.97% [DEBUG] sofia.c:3589 Write unlock external_without_reg 2021-12-01 11:32:15.533573 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_sip_interbi::Interbit from hash. 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia_reg.c:137 UN-Registering Interbit 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway Interbit 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread 2021-12-01 11:32:15.533573 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_sip_interbi Destroying SQL queue. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_sip_interbi Stopping SQL thread. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3576 Write lock external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3589 Write unlock external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:2308 MSG Thread Ended 2021-12-01 11:32:15.733564 98.97% [CONSOLE] switch_loadable_module.c:2369 mod_sofia unloaded. 2021-12-01 11:32:15.733564 98.97% [INFO] mod_sofia.c:6682 Starting initial message thread. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6060] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhonesystem] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [300] 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,PCMU] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,PCMU] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-capture [no] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 forward-unsolicited-mwi-notify [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 context [default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rfc2833-pt [101] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timer-name [soft] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 hold-music [local_stream://moh] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manage-presence [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 presence-hosts [172.16.0.30,172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 presence-privacy [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-negotiation [generous] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-all-packets [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-hold-timeout-sec [1800] 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5332 rtp-hold-timeout-sec deprecated use media_hold_timeout variable. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 challenge-realm [auto_from] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 nonce-ttl [60] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 pass-callee-id [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf_type [rfc2833] 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile default [sofia_reg_default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for default 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6080] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhonesystem] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60] 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,OPUS,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,OPUS,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_sip [sofia_reg_external_sip] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6090] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [Allnetworks] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_sip 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60] 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,OPUS,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,OPUS,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_without_reg [sofia_reg_external_without_reg] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6085] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhoneSystem 16.0.8.9] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60] 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,G729] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106] 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_sip_interbi [sofia_reg_external_sip_interbi] 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] mod_sofia.c:6698 Waiting for profiles to start 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:2290 MSG Thread 0 Started 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for default 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for default 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for default 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:default Starting SQL thread. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_sip 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_sip 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_sip 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_sip Starting SQL thread. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for default 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for default 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_without_reg Starting SQL thread. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_sip_interbi Starting SQL thread. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_sip 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_sip 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_without_reg 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_sip_interbi 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'ModulusIncoming' to profile 'external_sip' 2021-12-01 11:32:15.753562 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'ModulusOutgoing' to profile 'external_without_reg' 2021-12-01 11:32:15.753562 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'Interbit' to profile 'external_sip_interbi' 2021-12-01 11:32:16.753565 98.87% [NOTICE] sofia_reg.c:459 Registering ModulusIncoming 2021-12-01 11:32:16.753565 98.87% [NOTICE] sofia_reg.c:459 Registering Interbit 2021-12-01 11:32:16.913568 98.87% [DEBUG] sofia_reg.c:2665 Changing expire time to 89 by request of proxy sip:voips.modulus.gr:5060 2021-12-01 11:32:17.233569 98.87% [CONSOLE] switch_loadable_module.c:1768 Successfully Loaded [mod_sofia] 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:172 Adding Endpoint 'sofia' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:172 Adding Endpoint 'rtp' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:329 Adding Application 'sofia_sla' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:329 Adding Application 'sofia_stir_shaken_vs' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_gateway_data' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_username_of' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_contact' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_count_reg' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_dig' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_presence_data' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:419 Adding JSON API Function 'sofia.status' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:419 Adding JSON API Function 'sofia.status.info' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:621 Adding Chat interface 'sip' 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:681 Adding Management interface 'mod_sofia' OID[.1.3.6.1.4.1.27880.1001] 2021-12-01 11:32:34.833562 99.50% [NOTICE] switch_channel.c:1123 New Channel sofia/external_sip_interbi/2105777677@46.4.60.4 [0846d3cb-dd62-441c-9f72-da9153ed2df1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_NEW (Cur 1 Tot 46) 2021-12-01 11:32:34.833562 99.50% [INFO] sofia.c:10462 sofia/external_sip_interbi/2105777677@46.4.60.4 receiving invite from 46.4.60.4:5070 version: 1.10.7 -release-19-883d2cb662 64bit call-id: 386da1b0-cd3d-123a-f4aa-6c626da7bbd3 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 46.4.60.4:0. 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:10585 IP 46.4.60.4 Approved by acl "default[]". Access Granted. 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:7499 Channel sofia/external_sip_interbi/2105777677@46.4.60.4 entering state [received][100] 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:7509 Remote SDP: v=0 o=FreeSWITCH 1638328948 1638328949 IN IP4 46.4.60.4 s=FreeSWITCH c=IN IP4 46.4.60.4 t=0 0 m=audio 29406 RTP/AVP 8 18 9 101 13 a=rtpmap:8 PCMA/8000 a=rtpmap:18 G729/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1] 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:3870 Set Codec sofia/external_sip_interbi/2105777677@46.4.60.4 PCMA/8000 20 ms 160 samples 64000 bits 1 channels 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_codec.c:111 sofia/external_sip_interbi/2105777677@46.4.60.4 Original read codec set to PCMA:8 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5973 sofia/external_sip_interbi/2105777677@46.4.60.4 Set 2833 dtmf send payload to 101 recv payload to 101 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:7933 (sofia/external_sip_interbi/2105777677@46.4.60.4) State Change CS_NEW -> CS_INIT 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:600 (sofia/external_sip_interbi/2105777677@46.4.60.4) State NEW 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_INIT (Cur 1 Tot 46) 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:624 (sofia/external_sip_interbi/2105777677@46.4.60.4) State INIT 2021-12-01 11:32:34.833562 99.50% [DEBUG] mod_sofia.c:97 sofia/external_sip_interbi/2105777677@46.4.60.4 SOFIA INIT 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:40 sofia/external_sip_interbi/2105777677@46.4.60.4 Standard INIT 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:48 (sofia/external_sip_interbi/2105777677@46.4.60.4) State Change CS_INIT -> CS_ROUTING 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:624 (sofia/external_sip_interbi/2105777677@46.4.60.4) State INIT going to sleep 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:581 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_ROUTING (Cur 1 Tot 46) 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_channel.c:2380 (sofia/external_sip_interbi/2105777677@46.4.60.4) Callstate Change DOWN -> RINGING 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:640 (sofia/external_sip_interbi/2105777677@46.4.60.4) State ROUTING 2021-12-01 11:32:34.833562 99.50% [DEBUG] mod_sofia.c:158 sofia/external_sip_interbi/2105777677@46.4.60.4 SOFIA ROUTING 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_state_machine.c:230 sofia/external_sip_interbi/2105777677@46.4.60.4 Standard ROUTING 2021-12-01 11:32:34.833562 99.50% [INFO] mod_dialplan_xml.c:639 Processing 2105777677 <2105777677>->HNR-90057 in context default 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_CONF] Query :SELECT name,value FROM `system` WHERE group_title IN ('global','opensips','callingcard','calls','InternationalPrefixes') 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons 2021-12-01 11:32:34.853563 99.60% [ERR] mod_lua.cpp:202 /usr/share/freeswitch/scripts/astpp/scripts/astpp.dialplan.lua: corrupted precompiled chunk stack traceback: [C]: in function 'dofile' ./astpp/astpp_deb9.lua:87: in main chunk 2021-12-01 11:32:34.853563 99.60% [ERR] mod_lua.cpp:270 LUA script parse/execute error! 2021-12-01 11:32:34.853563 99.60% [WARNING] mod_dialplan_xml.c:669 Context default not found 2021-12-01 11:32:34.853563 99.60% [INFO] switch_core_state_machine.c:306 No Route, Aborting 2021-12-01 11:32:34.853563 99.60% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/external_sip_interbi/2105777677@46.4.60.4 [CS_ROUTING] [NO_ROUTE_DESTINATION] 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:640 (sofia/external_sip_interbi/2105777677@46.4.60.4) State ROUTING going to sleep 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:581 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_HANGUP (Cur 1 Tot 46) 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:844 (sofia/external_sip_interbi/2105777677@46.4.60.4) Callstate Change RINGING -> HANGUP 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:846 (sofia/external_sip_interbi/2105777677@46.4.60.4) State HANGUP 2021-12-01 11:32:34.853563 99.60% [DEBUG] mod_sofia.c:468 Channel sofia/external_sip_interbi/2105777677@46.4.60.4 hanging up, cause: NO_ROUTE_DESTINATION 2021-12-01 11:32:34.853563 99.60% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:59 sofia/external_sip_interbi/2105777677@46.4.60.4 Standard HANGUP, cause: NO_ROUTE_DESTINATION 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:846 (sofia/external_sip_interbi/2105777677@46.4.60.4) State HANGUP going to sleep 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:616 (sofia/external_sip_interbi/2105777677@46.4.60.4) State Change CS_HANGUP -> CS_REPORTING 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:581 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_REPORTING (Cur 1 Tot 46) 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:932 (sofia/external_sip_interbi/2105777677@46.4.60.4) State REPORTING 2021-12-01 11:32:34.853563 99.60% [INFO] mod_json_cdr.c:271 Process [0846d3cb-dd62-441c-9f72-da9153ed2df1.cdr.json] 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:168 sofia/external_sip_interbi/2105777677@46.4.60.4 Standard REPORTING, cause: NO_ROUTE_DESTINATION 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:932 (sofia/external_sip_interbi/2105777677@46.4.60.4) State REPORTING going to sleep 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:607 (sofia/external_sip_interbi/2105777677@46.4.60.4) State Change CS_REPORTING -> CS_DESTROY 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_session.c:1753 Session 46 (sofia/external_sip_interbi/2105777677@46.4.60.4) Locked, Waiting on external entities 2021-12-01 11:32:34.853563 99.60% [NOTICE] switch_core_session.c:1771 Session 46 (sofia/external_sip_interbi/2105777677@46.4.60.4) Ended 2021-12-01 11:32:34.853563 99.60% [NOTICE] switch_core_session.c:1775 Close Channel sofia/external_sip_interbi/2105777677@46.4.60.4 [CS_DESTROY] 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:735 (sofia/external_sip_interbi/2105777677@46.4.60.4) Running State Change CS_DESTROY (Cur 0 Tot 46) 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:745 (sofia/external_sip_interbi/2105777677@46.4.60.4) State DESTROY 2021-12-01 11:32:34.853563 99.60% [DEBUG] mod_sofia.c:379 sofia/external_sip_interbi/2105777677@46.4.60.4 SOFIA DESTROY 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:175 sofia/external_sip_interbi/2105777677@46.4.60.4 Standard DESTROY 2021-12-01 11:32:34.853563 99.60% [DEBUG] switch_core_state_machine.c:745 (sofia/external_sip_interbi/2105777677@46.4.60.4) State DESTROY going to sleep 2021-12-01 11:33:10.793569 99.07% [DEBUG] sofia_reg.c:2665 Changing expire time to 115 by request of proxy sip:voips.modulus.gr:5060