From Torrid Mousedeer, 3 Years ago, written in Plain Text.
- view diff
Embed
  1. 021-12-01 11:32:09.513557 99.07% [ERR] switch_stun.c:900 STUN Failed! [Timeout]
  2. 2021-12-01 11:32:09.513557 99.07% [ERR] switch_xml.c:175 stun-set failed.
  3. 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
  4. 2021-12-01 11:32:14.533564 99.03% [ERR] switch_stun.c:900 STUN Failed! [Timeout]
  5. 2021-12-01 11:32:14.533564 99.03% [ERR] switch_xml.c:175 stun-set failed.
  6. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1115 Write lock interface 'sofia' to wait for existing references.
  7. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1123 Deleting Endpoint 'sofia'
  8. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1115 Write lock interface 'rtp' to wait for existing references.
  9. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1123 Deleting Endpoint 'rtp'
  10. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1228 Deleting Application 'sofia_sla'
  11. 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.
  12. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1228 Deleting Application 'sofia_stir_shaken_vs'
  13. 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.
  14. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia'
  15. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1285 Write lock interface 'sofia' to wait for existing references.
  16. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_gateway_data'
  17. 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.
  18. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_username_of'
  19. 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.
  20. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_contact'
  21. 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.
  22. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_count_reg'
  23. 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.
  24. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_dig'
  25. 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.
  26. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1283 Deleting API Function 'sofia_presence_data'
  27. 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.
  28. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1315 Deleting API Function 'sofia.status'
  29. 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.
  30. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1315 Deleting API Function 'sofia.status.info'
  31. 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.
  32. 2021-12-01 11:32:14.553556 99.03% [DEBUG] switch_loadable_module.c:1535 Write lock interface 'sip' to wait for existing references.
  33. 2021-12-01 11:32:14.553556 99.03% [NOTICE] switch_loadable_module.c:1544 Deleting Chat interface 'sip'
  34. 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]
  35. 2021-12-01 11:32:14.553556 99.03% [CONSOLE] switch_loadable_module.c:2349 Stopping: mod_sofia
  36. 2021-12-01 11:32:14.573566 99.03% [INFO] switch_time.c:1430 Timezone reloaded 1750 definitions
  37. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::notify_refer
  38. 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
  39. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::unregister
  40. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::profile_start
  41. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::reinvite
  42. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::replaced
  43. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::transferor
  44. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::transferee
  45. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::error
  46. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::intercepted
  47. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_state
  48. 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
  49. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_delete
  50. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::expire
  51. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register_attempt
  52. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register_failure
  53. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::pre_register
  54. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::register
  55. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::gateway_add
  56. 2021-12-01 11:32:14.573566 99.03% [NOTICE] switch_event.c:467 Subclass reservation deleted for mod_sofia.c:sofia::bye_response
  57. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_IN
  58. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_OUT
  59. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PRESENCE_PROBE
  60. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:MESSAGE_WAITING
  61. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:ROSTER
  62. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:CONFERENCE_DATA
  63. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:TRAP
  64. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:NOTIFY
  65. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:PHONE_FEATURE
  66. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:SEND_MESSAGE
  67. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:SEND_INFO
  68. 2021-12-01 11:32:14.573566 99.03% [DEBUG] switch_event.c:2138 Event Binding deleted for mod_sofia:CUSTOM
  69. 2021-12-01 11:32:14.573566 99.03% [CONSOLE] sofia_presence.c:1664 Event Thread Ended
  70. 2021-12-01 11:32:14.733583 99.03% [NOTICE] sofia.c:3504 Waiting for worker thread
  71. 2021-12-01 11:32:14.733583 99.03% [INFO] switch_core_sqldb.c:1876 sofia:default Destroying SQL queue.
  72. 2021-12-01 11:32:14.933575 98.97% [INFO] switch_core_sqldb.c:1827 sofia:default Stopping SQL thread.
  73. 2021-12-01 11:32:14.933575 98.97% [DEBUG] sofia.c:3576 Write lock default
  74. 2021-12-01 11:32:14.933575 98.97% [DEBUG] sofia.c:3589 Write unlock default
  75. 2021-12-01 11:32:14.973577 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_sip::ModulusIncoming from hash.
  76. 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia_reg.c:137 UN-Registering ModulusIncoming
  77. 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway ModulusIncoming
  78. 2021-12-01 11:32:14.973577 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread
  79. 2021-12-01 11:32:14.973577 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_sip Destroying SQL queue.
  80. 2021-12-01 11:32:15.133572 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_without_reg::ModulusOutgoing from hash.
  81. 2021-12-01 11:32:15.133572 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway ModulusOutgoing
  82. 2021-12-01 11:32:15.133572 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread
  83. 2021-12-01 11:32:15.133572 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_without_reg Destroying SQL queue.
  84. 2021-12-01 11:32:15.173578 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_sip Stopping SQL thread.
  85. 2021-12-01 11:32:15.173578 98.97% [DEBUG] sofia.c:3576 Write lock external_sip
  86. 2021-12-01 11:32:15.173578 98.97% [DEBUG] sofia.c:3589 Write unlock external_sip
  87. 2021-12-01 11:32:15.333576 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_without_reg Stopping SQL thread.
  88. 2021-12-01 11:32:15.333576 98.97% [DEBUG] sofia.c:3576 Write lock external_without_reg
  89. 2021-12-01 11:32:15.333576 98.97% [DEBUG] sofia.c:3589 Write unlock external_without_reg
  90. 2021-12-01 11:32:15.533573 98.97% [DEBUG] sofia_reg.c:324 Removing gateway external_sip_interbi::Interbit from hash.
  91. 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia_reg.c:137 UN-Registering Interbit
  92. 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia_reg.c:338 Deleted gateway Interbit
  93. 2021-12-01 11:32:15.533573 98.97% [NOTICE] sofia.c:3504 Waiting for worker thread
  94. 2021-12-01 11:32:15.533573 98.97% [INFO] switch_core_sqldb.c:1876 sofia:external_sip_interbi Destroying SQL queue.
  95. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1827 sofia:external_sip_interbi Stopping SQL thread.
  96. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3576 Write lock external_sip_interbi
  97. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3589 Write unlock external_sip_interbi
  98. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:2308 MSG Thread Ended
  99. 2021-12-01 11:32:15.733564 98.97% [CONSOLE] switch_loadable_module.c:2369 mod_sofia unloaded.
  100. 2021-12-01 11:32:15.733564 98.97% [INFO] mod_sofia.c:6682 Starting initial message thread.
  101. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30]
  102. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6060]
  103. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30]
  104. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML]
  105. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhonesystem]
  106. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0]
  107. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no]
  108. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false]
  109. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true]
  110. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true]
  111. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false]
  112. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true]
  113. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true]
  114. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0]
  115. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false]
  116. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false]
  117. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [false]
  118. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false]
  119. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [300]
  120. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable.
  121. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000]
  122. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [true]
  123. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [true]
  124. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false]
  125. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120]
  126. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800]
  127. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true]
  128. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default]
  129. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,PCMU]
  130. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,PCMU]
  131. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false]
  132. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-capture [no]
  133. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 forward-unsolicited-mwi-notify [false]
  134. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 context [default]
  135. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rfc2833-pt [101]
  136. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timer-name [soft]
  137. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 hold-music [local_stream://moh]
  138. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manage-presence [true]
  139. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 presence-hosts [172.16.0.30,172.16.0.30]
  140. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 presence-privacy [false]
  141. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-negotiation [generous]
  142. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-all-packets [false]
  143. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106]
  144. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106]
  145. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-hold-timeout-sec [1800]
  146. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5332 rtp-hold-timeout-sec deprecated use media_hold_timeout variable.
  147. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 challenge-realm [auto_from]
  148. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 nonce-ttl [60]
  149. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 pass-callee-id [false]
  150. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf_type [rfc2833]
  151. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts
  152. 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile default [sofia_reg_default]
  153. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for default
  154. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30]
  155. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6080]
  156. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30]
  157. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML]
  158. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhonesystem]
  159. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0]
  160. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no]
  161. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false]
  162. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true]
  163. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true]
  164. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false]
  165. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true]
  166. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true]
  167. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0]
  168. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false]
  169. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false]
  170. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true]
  171. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false]
  172. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60]
  173. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable.
  174. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000]
  175. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false]
  176. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false]
  177. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false]
  178. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120]
  179. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800]
  180. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [false]
  181. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default]
  182. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,OPUS,G729]
  183. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,OPUS,G729]
  184. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false]
  185. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106]
  186. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106]
  187. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds
  188. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts
  189. 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_sip [sofia_reg_external_sip]
  190. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30]
  191. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6090]
  192. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30]
  193. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML]
  194. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [Allnetworks]
  195. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0]
  196. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_sip
  197. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no]
  198. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false]
  199. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true]
  200. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true]
  201. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false]
  202. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true]
  203. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true]
  204. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0]
  205. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false]
  206. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false]
  207. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true]
  208. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false]
  209. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60]
  210. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable.
  211. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000]
  212. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false]
  213. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false]
  214. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false]
  215. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120]
  216. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800]
  217. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true]
  218. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default]
  219. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,OPUS,G729]
  220. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,OPUS,G729]
  221. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false]
  222. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106]
  223. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106]
  224. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds
  225. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts
  226. 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_without_reg [sofia_reg_external_without_reg]
  227. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-ip [172.16.0.30]
  228. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-port [6085]
  229. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-ip [172.16.0.30]
  230. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dialplan [XML]
  231. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 user-agent-string [3CXPhoneSystem 16.0.8.9]
  232. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 debug [0]
  233. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 sip-trace [no]
  234. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 tls [false]
  235. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-reg-force-matching-username [true]
  236. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transcoding [true]
  237. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 all-reg-options-ping [false]
  238. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_without_reg
  239. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 unregister-on-options-fail [true]
  240. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 log-auth-failures [true]
  241. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 status [0]
  242. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-bypass-media [false]
  243. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-proxy-media [false]
  244. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 disable-transfer [true]
  245. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-100rel [false]
  246. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 rtp-timeout-sec [60]
  247. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:5325 rtp-timeout-sec deprecated use media_timeout variable.
  248. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 dtmf-duration [2000]
  249. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 manual-redirect [false]
  250. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 aggressive-nat-detection [false]
  251. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 enable-timer [false]
  252. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 minimum-session-expires [120]
  253. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 session-timeout-pt [1800]
  254. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 auth-calls [true]
  255. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 apply-inbound-acl [default]
  256. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-codec-prefs [PCMA,G729]
  257. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 outbound-codec-prefs [PCMA,G729]
  258. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 inbound-late-negotiation [false]
  259. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-sip-ip [94.130.242.106]
  260. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:4720 ext-rtp-ip [94.130.242.106]
  261. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6184 Setting nonce TTL to 60 seconds
  262. 2021-12-01 11:32:15.733564 98.97% [INFO] sofia.c:6189 Setting MAX Auth Validity to 0 Attempts
  263. 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia.c:6356 Started Profile external_sip_interbi [sofia_reg_external_sip_interbi]
  264. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3156 Creating agent for external_sip_interbi
  265. 2021-12-01 11:32:15.733564 98.97% [DEBUG] mod_sofia.c:6698 Waiting for profiles to start
  266. 2021-12-01 11:32:15.733564 98.97% [WARNING] sofia.c:2290 MSG Thread 0 Started
  267. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for default
  268. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for default
  269. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for default
  270. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:default Starting SQL thread.
  271. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_sip_interbi
  272. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_sip_interbi
  273. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_sip_interbi
  274. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_without_reg
  275. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_without_reg
  276. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_without_reg
  277. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3319 Created agent for external_sip
  278. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3369 Set params for external_sip
  279. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3416 Activated db for external_sip
  280. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_sip Starting SQL thread.
  281. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for default
  282. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for default
  283. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_without_reg Starting SQL thread.
  284. 2021-12-01 11:32:15.733564 98.97% [INFO] switch_core_sqldb.c:1842 sofia:external_sip_interbi Starting SQL thread.
  285. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_sip
  286. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_sip
  287. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_without_reg
  288. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_without_reg
  289. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3454 Starting thread for external_sip_interbi
  290. 2021-12-01 11:32:15.733564 98.97% [DEBUG] sofia.c:3046 Launching worker thread for external_sip_interbi
  291. 2021-12-01 11:32:15.733564 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'ModulusIncoming' to profile 'external_sip'
  292. 2021-12-01 11:32:15.753562 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'ModulusOutgoing' to profile 'external_without_reg'
  293. 2021-12-01 11:32:15.753562 98.97% [NOTICE] sofia_reg.c:3735 Added gateway 'Interbit' to profile 'external_sip_interbi'
  294. 2021-12-01 11:32:16.753565 98.87% [NOTICE] sofia_reg.c:459 Registering ModulusIncoming
  295. 2021-12-01 11:32:16.753565 98.87% [NOTICE] sofia_reg.c:459 Registering Interbit
  296. 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
  297. 2021-12-01 11:32:17.233569 98.87% [CONSOLE] switch_loadable_module.c:1768 Successfully Loaded [mod_sofia]
  298. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:172 Adding Endpoint 'sofia'
  299. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:172 Adding Endpoint 'rtp'
  300. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:329 Adding Application 'sofia_sla'
  301. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:329 Adding Application 'sofia_stir_shaken_vs'
  302. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia'
  303. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_gateway_data'
  304. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_username_of'
  305. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_contact'
  306. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_count_reg'
  307. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_dig'
  308. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:389 Adding API Function 'sofia_presence_data'
  309. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:419 Adding JSON API Function 'sofia.status'
  310. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:419 Adding JSON API Function 'sofia.status.info'
  311. 2021-12-01 11:32:17.233569 98.87% [NOTICE] switch_loadable_module.c:621 Adding Chat interface 'sip'
  312. 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]
  313. 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]
  314. 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)
  315. 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
  316. 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 46.4.60.4:0.
  317. 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:10585 IP 46.4.60.4 Approved by acl "default[]". Access Granted.
  318. 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]
  319. 2021-12-01 11:32:34.833562 99.50% [DEBUG] sofia.c:7509 Remote SDP:
  320. v=0
  321. o=FreeSWITCH 1638328948 1638328949 IN IP4 46.4.60.4
  322. s=FreeSWITCH
  323. c=IN IP4 46.4.60.4
  324. t=0 0
  325. m=audio 29406 RTP/AVP 8 18 9 101 13
  326. a=rtpmap:8 PCMA/8000
  327. a=rtpmap:18 G729/8000
  328. a=rtpmap:9 G722/8000
  329. a=rtpmap:101 telephone-event/8000
  330. a=fmtp:101 0-16
  331. a=ptime:20
  332.  
  333. 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]
  334. 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
  335. 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]
  336. 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]
  337. 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]
  338. 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
  339. 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]
  340. 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]
  341. 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  342. 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
  343. 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
  344. 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  345. 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
  346. 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
  347. 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
  348. 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)
  349. 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
  350. 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
  351. 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
  352. 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
  353. 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
  354. 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)
  355. 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
  356. 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
  357. 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
  358. 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
  359. 2021-12-01 11:32:34.833562 99.50% [INFO] mod_dialplan_xml.c:639 Processing 2105777677 <2105777677>->HNR-90057 in context default
  360. 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')
  361. 2021-12-01 11:32:34.833562 99.50% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  362. 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
  363. stack traceback:
  364.         [C]: in function 'dofile'
  365.         ./astpp/astpp_deb9.lua:87: in main chunk
  366. 2021-12-01 11:32:34.853563 99.60% [ERR] mod_lua.cpp:270 LUA script parse/execute error!
  367. 2021-12-01 11:32:34.853563 99.60% [WARNING] mod_dialplan_xml.c:669 Context default not found
  368. 2021-12-01 11:32:34.853563 99.60% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  369. 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]
  370. 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
  371. 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)
  372. 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
  373. 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
  374. 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
  375. 2021-12-01 11:32:34.853563 99.60% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  376. 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
  377. 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
  378. 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
  379. 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)
  380. 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
  381. 2021-12-01 11:32:34.853563 99.60% [INFO] mod_json_cdr.c:271 Process [0846d3cb-dd62-441c-9f72-da9153ed2df1.cdr.json]
  382. 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
  383. 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
  384. 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
  385. 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
  386. 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
  387. 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]
  388. 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)
  389. 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
  390. 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
  391. 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
  392. 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
  393. 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