From bernie, 1 Year ago, written in FreeSWITCH.
- go back
Embed
Viewing differences between and bernie invalid header
  1. span style="color: green; font-weight: bold;"> 2023-04-13 14:05:32.029086 97.90% [INFO] mod_dialplan_xml.c:639 Processing V4131405250000483084 <17813312611>->17814929087 in context default
  2. 2023-04-13 14:05:32.029086 97.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  3. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  4. 2023-04-13 14:05:32.029086 97.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')
  5. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  6. 2023-04-13 14:05:32.029086 97.90% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  7. 2023-04-13 14:05:32.029086 97.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
  8. 2023-04-13 14:05:32.029086 97.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  9. 2023-04-13 14:05:32.029086 97.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/17813312611@168.235.110.123 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  10. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/17813312611@168.235.110.123) State ROUTING going to sleep
  11. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17813312611@168.235.110.123) Running State Change CS_HANGUP (Cur 1 Tot 15363)
  12. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/17813312611@168.235.110.123) Callstate Change RINGING -> HANGUP
  13. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/17813312611@168.235.110.123) State HANGUP
  14. 2023-04-13 14:05:32.029086 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/17813312611@168.235.110.123 hanging up, cause: NO_ROUTE_DESTINATION
  15. 2023-04-13 14:05:32.029086 97.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  16. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/17813312611@168.235.110.123 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  17. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/17813312611@168.235.110.123) State HANGUP going to sleep
  18. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/17813312611@168.235.110.123) State Change CS_HANGUP -> CS_REPORTING
  19. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17813312611@168.235.110.123) Running State Change CS_REPORTING (Cur 1 Tot 15363)
  20. 2023-04-13 14:05:32.029086 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/17813312611@168.235.110.123) State REPORTING
  21. 2023-04-13 14:05:32.029086 97.90% [INFO] mod_json_cdr.c:271 Process [00a2d511-da63-4700-8872-1aac70c3dbf1.cdr.json]
  22. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/17813312611@168.235.110.123 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  23. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/17813312611@168.235.110.123) State REPORTING going to sleep
  24. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/17813312611@168.235.110.123) State Change CS_REPORTING -> CS_DESTROY
  25. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_session.c:1753 Session 15363 (sofia/default/17813312611@168.235.110.123) Locked, Waiting on external entities
  26. 2023-04-13 14:05:32.069024 97.90% [NOTICE] switch_core_session.c:1771 Session 15363 (sofia/default/17813312611@168.235.110.123) Ended
  27. 2023-04-13 14:05:32.069024 97.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/17813312611@168.235.110.123 [CS_DESTROY]
  28. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/17813312611@168.235.110.123) Running State Change CS_DESTROY (Cur 0 Tot 15363)
  29. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/17813312611@168.235.110.123) State DESTROY
  30. 2023-04-13 14:05:32.069024 97.90% [DEBUG] mod_sofia.c:379 sofia/default/17813312611@168.235.110.123 SOFIA DESTROY
  31. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/17813312611@168.235.110.123 Standard DESTROY
  32. 2023-04-13 14:05:32.069024 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/17813312611@168.235.110.123) State DESTROY going to sleep
  33. 2023-04-13 14:05:32.209034 97.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/14233101014@168.235.110.123 [01df6d71-ba9f-4874-b3e3-a7a12ce601c0]
  34. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/14233101014@168.235.110.123) Running State Change CS_NEW (Cur 1 Tot 15364)
  35. 2023-04-13 14:05:32.209034 97.90% [INFO] sofia.c:10462 sofia/default/14233101014@168.235.110.123 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 1ad81dc8014ba92c45b2cfeb2532407b@168.235.110.123:5060
  36. 2023-04-13 14:05:32.209034 97.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  37. 2023-04-13 14:05:32.209034 97.90% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  38. 2023-04-13 14:05:32.209034 97.90% [DEBUG] sofia.c:7499 Channel sofia/default/14233101014@168.235.110.123 entering state [received][100]
  39. 2023-04-13 14:05:32.209034 97.90% [DEBUG] sofia.c:7509 Remote SDP:
  40. v=0
  41. o=root 1552548836 1552548836 IN IP4 168.235.110.123
  42. s=Asterisk PBX 11.25.1-vici
  43. c=IN IP4 168.235.110.123
  44. t=0 0
  45. m=audio 17626 RTP/AVP 0 8 18 101
  46. a=rtpmap:0 PCMU/8000
  47. a=rtpmap:8 PCMA/8000
  48. a=rtpmap:18 G729/8000
  49. a=fmtp:18 annexb=no
  50. a=rtpmap:101 telephone-event/8000
  51. a=fmtp:101 0-16
  52. a=ptime:20
  53.  
  54. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  55. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  56. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  57. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  58. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  59. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  60. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  61. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  62. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  63. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  64. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  65. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  66. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  67. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/14233101014@168.235.110.123 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  68. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_codec.c:111 sofia/default/14233101014@168.235.110.123 Original read codec set to PCMU:0
  69. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  70. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_media.c:5973 sofia/default/14233101014@168.235.110.123 Set 2833 dtmf send payload to 101 recv payload to 101
  71. 2023-04-13 14:05:32.209034 97.90% [DEBUG] sofia.c:7933 (sofia/default/14233101014@168.235.110.123) State Change CS_NEW -> CS_INIT
  72. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/14233101014@168.235.110.123) State NEW
  73. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/14233101014@168.235.110.123) Running State Change CS_INIT (Cur 1 Tot 15364)
  74. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/14233101014@168.235.110.123) State INIT
  75. 2023-04-13 14:05:32.209034 97.90% [DEBUG] mod_sofia.c:97 sofia/default/14233101014@168.235.110.123 SOFIA INIT
  76. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/14233101014@168.235.110.123 Standard INIT
  77. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/14233101014@168.235.110.123) State Change CS_INIT -> CS_ROUTING
  78. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/14233101014@168.235.110.123) State INIT going to sleep
  79. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/14233101014@168.235.110.123) Running State Change CS_ROUTING (Cur 1 Tot 15364)
  80. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_channel.c:2380 (sofia/default/14233101014@168.235.110.123) Callstate Change DOWN -> RINGING
  81. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/14233101014@168.235.110.123) State ROUTING
  82. 2023-04-13 14:05:32.209034 97.90% [DEBUG] mod_sofia.c:158 sofia/default/14233101014@168.235.110.123 SOFIA ROUTING
  83. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/14233101014@168.235.110.123 Standard ROUTING
  84. 2023-04-13 14:05:32.209034 97.90% [INFO] mod_dialplan_xml.c:639 Processing V4131405250000490174 <14233101014>->14233550926 in context default
  85. 2023-04-13 14:05:32.209034 97.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  86. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  87. 2023-04-13 14:05:32.209034 97.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')
  88. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  89. 2023-04-13 14:05:32.209034 97.90% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  90. 2023-04-13 14:05:32.209034 97.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
  91. 2023-04-13 14:05:32.209034 97.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  92. 2023-04-13 14:05:32.209034 97.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/14233101014@168.235.110.123 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  93. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/14233101014@168.235.110.123) State ROUTING going to sleep
  94. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/14233101014@168.235.110.123) Running State Change CS_HANGUP (Cur 1 Tot 15364)
  95. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/14233101014@168.235.110.123) Callstate Change RINGING -> HANGUP
  96. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/14233101014@168.235.110.123) State HANGUP
  97. 2023-04-13 14:05:32.209034 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/14233101014@168.235.110.123 hanging up, cause: NO_ROUTE_DESTINATION
  98. 2023-04-13 14:05:32.209034 97.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  99. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/14233101014@168.235.110.123 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  100. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/14233101014@168.235.110.123) State HANGUP going to sleep
  101. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/14233101014@168.235.110.123) State Change CS_HANGUP -> CS_REPORTING
  102. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/14233101014@168.235.110.123) Running State Change CS_REPORTING (Cur 1 Tot 15364)
  103. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/14233101014@168.235.110.123) State REPORTING
  104. 2023-04-13 14:05:32.209034 97.90% [INFO] mod_json_cdr.c:271 Process [01df6d71-ba9f-4874-b3e3-a7a12ce601c0.cdr.json]
  105. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/14233101014@168.235.110.123 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  106. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/14233101014@168.235.110.123) State REPORTING going to sleep
  107. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/14233101014@168.235.110.123) State Change CS_REPORTING -> CS_DESTROY
  108. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_session.c:1753 Session 15364 (sofia/default/14233101014@168.235.110.123) Locked, Waiting on external entities
  109. 2023-04-13 14:05:32.209034 97.90% [NOTICE] switch_core_session.c:1771 Session 15364 (sofia/default/14233101014@168.235.110.123) Ended
  110. 2023-04-13 14:05:32.209034 97.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/14233101014@168.235.110.123 [CS_DESTROY]
  111. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/14233101014@168.235.110.123) Running State Change CS_DESTROY (Cur 0 Tot 15364)
  112. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/14233101014@168.235.110.123) State DESTROY
  113. 2023-04-13 14:05:32.209034 97.90% [DEBUG] mod_sofia.c:379 sofia/default/14233101014@168.235.110.123 SOFIA DESTROY
  114. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/14233101014@168.235.110.123 Standard DESTROY
  115. 2023-04-13 14:05:32.209034 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/14233101014@168.235.110.123) State DESTROY going to sleep
  116. 2023-04-13 14:05:32.889066 97.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/19049701843@168.235.110.123 [e2490474-1a75-4ac0-87f7-12fa26227cf3]
  117. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/19049701843@168.235.110.123) Running State Change CS_NEW (Cur 1 Tot 15365)
  118. 2023-04-13 14:05:32.889066 97.90% [INFO] sofia.c:10462 sofia/default/19049701843@168.235.110.123 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 37ec331f0a762d113e1c29417e4537c7@168.235.110.123:5060
  119. 2023-04-13 14:05:32.889066 97.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  120. 2023-04-13 14:05:32.889066 97.90% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  121. 2023-04-13 14:05:32.889066 97.90% [DEBUG] sofia.c:7499 Channel sofia/default/19049701843@168.235.110.123 entering state [received][100]
  122. 2023-04-13 14:05:32.889066 97.90% [DEBUG] sofia.c:7509 Remote SDP:
  123. v=0
  124. o=root 613615751 613615751 IN IP4 168.235.110.123
  125. s=Asterisk PBX 11.25.1-vici
  126. c=IN IP4 168.235.110.123
  127. t=0 0
  128. m=audio 16004 RTP/AVP 0 8 18 101
  129. a=rtpmap:0 PCMU/8000
  130. a=rtpmap:8 PCMA/8000
  131. a=rtpmap:18 G729/8000
  132. a=fmtp:18 annexb=no
  133. a=rtpmap:101 telephone-event/8000
  134. a=fmtp:101 0-16
  135. a=ptime:20
  136.  
  137. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  138. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  139. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  140. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  141. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  142. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  143. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  144. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  145. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  146. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  147. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  148. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  149. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  150. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/19049701843@168.235.110.123 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  151. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_codec.c:111 sofia/default/19049701843@168.235.110.123 Original read codec set to PCMU:0
  152. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  153. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_media.c:5973 sofia/default/19049701843@168.235.110.123 Set 2833 dtmf send payload to 101 recv payload to 101
  154. 2023-04-13 14:05:32.889066 97.90% [DEBUG] sofia.c:7933 (sofia/default/19049701843@168.235.110.123) State Change CS_NEW -> CS_INIT
  155. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/19049701843@168.235.110.123) State NEW
  156. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/19049701843@168.235.110.123) Running State Change CS_INIT (Cur 1 Tot 15365)
  157. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/19049701843@168.235.110.123) State INIT
  158. 2023-04-13 14:05:32.889066 97.90% [DEBUG] mod_sofia.c:97 sofia/default/19049701843@168.235.110.123 SOFIA INIT
  159. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/19049701843@168.235.110.123 Standard INIT
  160. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/19049701843@168.235.110.123) State Change CS_INIT -> CS_ROUTING
  161. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/19049701843@168.235.110.123) State INIT going to sleep
  162. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/19049701843@168.235.110.123) Running State Change CS_ROUTING (Cur 1 Tot 15365)
  163. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_channel.c:2380 (sofia/default/19049701843@168.235.110.123) Callstate Change DOWN -> RINGING
  164. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/19049701843@168.235.110.123) State ROUTING
  165. 2023-04-13 14:05:32.889066 97.90% [DEBUG] mod_sofia.c:158 sofia/default/19049701843@168.235.110.123 SOFIA ROUTING
  166. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/19049701843@168.235.110.123 Standard ROUTING
  167. 2023-04-13 14:05:32.889066 97.90% [INFO] mod_dialplan_xml.c:639 Processing V4131405250000456724 <19049701843>->19042377510 in context default
  168. 2023-04-13 14:05:32.889066 97.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  169. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  170. 2023-04-13 14:05:32.889066 97.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')
  171. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  172. 2023-04-13 14:05:32.889066 97.90% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  173. 2023-04-13 14:05:32.889066 97.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
  174. 2023-04-13 14:05:32.889066 97.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  175. 2023-04-13 14:05:32.889066 97.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/19049701843@168.235.110.123 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  176. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/19049701843@168.235.110.123) State ROUTING going to sleep
  177. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/19049701843@168.235.110.123) Running State Change CS_HANGUP (Cur 1 Tot 15365)
  178. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/19049701843@168.235.110.123) Callstate Change RINGING -> HANGUP
  179. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/19049701843@168.235.110.123) State HANGUP
  180. 2023-04-13 14:05:32.889066 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/19049701843@168.235.110.123 hanging up, cause: NO_ROUTE_DESTINATION
  181. 2023-04-13 14:05:32.889066 97.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  182. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/19049701843@168.235.110.123 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  183. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/19049701843@168.235.110.123) State HANGUP going to sleep
  184. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/19049701843@168.235.110.123) State Change CS_HANGUP -> CS_REPORTING
  185. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/19049701843@168.235.110.123) Running State Change CS_REPORTING (Cur 1 Tot 15365)
  186. 2023-04-13 14:05:32.889066 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/19049701843@168.235.110.123) State REPORTING
  187. 2023-04-13 14:05:32.889066 97.90% [INFO] mod_json_cdr.c:271 Process [e2490474-1a75-4ac0-87f7-12fa26227cf3.cdr.json]
  188. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:168 sofia/default/19049701843@168.235.110.123 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  189. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/19049701843@168.235.110.123) State REPORTING going to sleep
  190. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:607 (sofia/default/19049701843@168.235.110.123) State Change CS_REPORTING -> CS_DESTROY
  191. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_session.c:1753 Session 15365 (sofia/default/19049701843@168.235.110.123) Locked, Waiting on external entities
  192. 2023-04-13 14:05:32.909048 97.90% [NOTICE] switch_core_session.c:1771 Session 15365 (sofia/default/19049701843@168.235.110.123) Ended
  193. 2023-04-13 14:05:32.909048 97.90% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/19049701843@168.235.110.123 [CS_DESTROY]
  194. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:735 (sofia/default/19049701843@168.235.110.123) Running State Change CS_DESTROY (Cur 0 Tot 15365)
  195. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/19049701843@168.235.110.123) State DESTROY
  196. 2023-04-13 14:05:32.909048 97.90% [DEBUG] mod_sofia.c:379 sofia/default/19049701843@168.235.110.123 SOFIA DESTROY
  197. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:175 sofia/default/19049701843@168.235.110.123 Standard DESTROY
  198. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:745 (sofia/default/19049701843@168.235.110.123) State DESTROY going to sleep
  199. 2023-04-13 14:05:32.909048 97.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/16306968030@192.158.239.154 [36572fb3-3c66-426e-bd4e-e91a22809b35]
  200. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16306968030@192.158.239.154) Running State Change CS_NEW (Cur 1 Tot 15366)
  201. 2023-04-13 14:05:32.909048 97.90% [INFO] sofia.c:10462 sofia/default/16306968030@192.158.239.154 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 3a4c153a6c6a1d824c976201491bee02@192.158.239.154:5060
  202. 2023-04-13 14:05:32.909048 97.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  203. 2023-04-13 14:05:32.909048 97.90% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  204. 2023-04-13 14:05:32.909048 97.90% [DEBUG] sofia.c:7499 Channel sofia/default/16306968030@192.158.239.154 entering state [received][100]
  205. 2023-04-13 14:05:32.909048 97.90% [DEBUG] sofia.c:7509 Remote SDP:
  206. v=0
  207. o=root 1859827084 1859827084 IN IP4 192.158.239.154
  208. s=Asterisk PBX 13.29.2-vici
  209. c=IN IP4 192.158.239.154
  210. t=0 0
  211. m=audio 11188 RTP/AVP 0 8 18 101
  212. a=rtpmap:0 PCMU/8000
  213. a=rtpmap:8 PCMA/8000
  214. a=rtpmap:18 G729/8000
  215. a=fmtp:18 annexb=no
  216. a=rtpmap:101 telephone-event/8000
  217. a=fmtp:101 0-16
  218. a=ptime:20
  219. a=maxptime:150
  220.  
  221. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  222. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  223. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  224. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  225. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  226. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  227. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  228. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  229. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  230. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  231. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  232. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  233. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  234. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/16306968030@192.158.239.154 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  235. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_codec.c:111 sofia/default/16306968030@192.158.239.154 Original read codec set to PCMU:0
  236. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  237. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_media.c:5973 sofia/default/16306968030@192.158.239.154 Set 2833 dtmf send payload to 101 recv payload to 101
  238. 2023-04-13 14:05:32.909048 97.90% [DEBUG] sofia.c:7933 (sofia/default/16306968030@192.158.239.154) State Change CS_NEW -> CS_INIT
  239. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/16306968030@192.158.239.154) State NEW
  240. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16306968030@192.158.239.154) Running State Change CS_INIT (Cur 1 Tot 15366)
  241. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/16306968030@192.158.239.154) State INIT
  242. 2023-04-13 14:05:32.909048 97.90% [DEBUG] mod_sofia.c:97 sofia/default/16306968030@192.158.239.154 SOFIA INIT
  243. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/16306968030@192.158.239.154 Standard INIT
  244. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/16306968030@192.158.239.154) State Change CS_INIT -> CS_ROUTING
  245. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/16306968030@192.158.239.154) State INIT going to sleep
  246. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16306968030@192.158.239.154) Running State Change CS_ROUTING (Cur 1 Tot 15366)
  247. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_channel.c:2380 (sofia/default/16306968030@192.158.239.154) Callstate Change DOWN -> RINGING
  248. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/16306968030@192.158.239.154) State ROUTING
  249. 2023-04-13 14:05:32.909048 97.90% [DEBUG] mod_sofia.c:158 sofia/default/16306968030@192.158.239.154 SOFIA ROUTING
  250. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/16306968030@192.158.239.154 Standard ROUTING
  251. 2023-04-13 14:05:32.909048 97.90% [INFO] mod_dialplan_xml.c:639 Processing V4131405250003798137 <16306968030>->14859345516302001033 in context default
  252. 2023-04-13 14:05:32.909048 97.90% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  253. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  254. 2023-04-13 14:05:32.909048 97.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')
  255. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  256. 2023-04-13 14:05:32.909048 97.90% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  257. 2023-04-13 14:05:32.909048 97.90% [WARNING] mod_dialplan_xml.c:669 Context default not found
  258. 2023-04-13 14:05:32.909048 97.90% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  259. 2023-04-13 14:05:32.909048 97.90% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/16306968030@192.158.239.154 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  260. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/16306968030@192.158.239.154) State ROUTING going to sleep
  261. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16306968030@192.158.239.154) Running State Change CS_HANGUP (Cur 1 Tot 15366)
  262. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:844 (sofia/default/16306968030@192.158.239.154) Callstate Change RINGING -> HANGUP
  263. 2023-04-13 14:05:32.909048 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/16306968030@192.158.239.154) State HANGUP
  264. 2023-04-13 14:05:32.909048 97.90% [DEBUG] mod_sofia.c:468 Channel sofia/default/16306968030@192.158.239.154 hanging up, cause: NO_ROUTE_DESTINATION
  265. 2023-04-13 14:05:32.949001 97.90% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  266. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:59 sofia/default/16306968030@192.158.239.154 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  267. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:846 (sofia/default/16306968030@192.158.239.154) State HANGUP going to sleep
  268. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:616 (sofia/default/16306968030@192.158.239.154) State Change CS_HANGUP -> CS_REPORTING
  269. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16306968030@192.158.239.154) Running State Change CS_REPORTING (Cur 1 Tot 15366)
  270. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:932 (sofia/default/16306968030@192.158.239.154) State REPORTING
  271. 2023-04-13 14:05:32.949001 97.90% [INFO] mod_json_cdr.c:271 Process [36572fb3-3c66-426e-bd4e-e91a22809b35.cdr.json]
  272. 2023-04-13 14:05:32.949001 97.90% [NOTICE] switch_channel.c:1123 New Channel sofia/default/17879392883@149.28.206.5 [120d4ff5-9fe2-4f76-849d-501f40414ac2]
  273. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17879392883@149.28.206.5) Running State Change CS_NEW (Cur 2 Tot 15367)
  274. 2023-04-13 14:05:32.949001 97.90% [INFO] sofia.c:10462 sofia/default/17879392883@149.28.206.5 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 384da5543416569e542f63610d07d088@149.28.206.5:5060
  275. 2023-04-13 14:05:32.949001 97.90% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  276. 2023-04-13 14:05:32.949001 97.90% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  277. 2023-04-13 14:05:32.949001 97.90% [DEBUG] sofia.c:7499 Channel sofia/default/17879392883@149.28.206.5 entering state [received][100]
  278. 2023-04-13 14:05:32.949001 97.90% [DEBUG] sofia.c:7509 Remote SDP:
  279. v=0
  280. o=root 1824995045 1824995045 IN IP4 149.28.206.5
  281. s=Asterisk PBX 13.27.1-vici
  282. c=IN IP4 149.28.206.5
  283. t=0 0
  284. m=audio 10220 RTP/AVP 0 8 101
  285. a=rtpmap:0 PCMU/8000
  286. a=rtpmap:8 PCMA/8000
  287. a=rtpmap:101 telephone-event/8000
  288. a=fmtp:101 0-16
  289. a=ptime:20
  290. a=maxptime:150
  291.  
  292. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  293. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  294. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  295. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  296. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  297. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  298. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  299. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  300. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  301. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/17879392883@149.28.206.5 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  302. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_codec.c:111 sofia/default/17879392883@149.28.206.5 Original read codec set to PCMU:0
  303. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  304. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_media.c:5973 sofia/default/17879392883@149.28.206.5 Set 2833 dtmf send payload to 101 recv payload to 101
  305. 2023-04-13 14:05:32.949001 97.90% [DEBUG] sofia.c:7933 (sofia/default/17879392883@149.28.206.5) State Change CS_NEW -> CS_INIT
  306. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:600 (sofia/default/17879392883@149.28.206.5) State NEW
  307. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17879392883@149.28.206.5) Running State Change CS_INIT (Cur 2 Tot 15367)
  308. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/17879392883@149.28.206.5) State INIT
  309. 2023-04-13 14:05:32.949001 97.90% [DEBUG] mod_sofia.c:97 sofia/default/17879392883@149.28.206.5 SOFIA INIT
  310. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:40 sofia/default/17879392883@149.28.206.5 Standard INIT
  311. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:48 (sofia/default/17879392883@149.28.206.5) State Change CS_INIT -> CS_ROUTING
  312. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:624 (sofia/default/17879392883@149.28.206.5) State INIT going to sleep
  313. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17879392883@149.28.206.5) Running State Change CS_ROUTING (Cur 2 Tot 15367)
  314. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_channel.c:2380 (sofia/default/17879392883@149.28.206.5) Callstate Change DOWN -> RINGING
  315. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:640 (sofia/default/17879392883@149.28.206.5) State ROUTING
  316. 2023-04-13 14:05:32.949001 97.90% [DEBUG] mod_sofia.c:158 sofia/default/17879392883@149.28.206.5 SOFIA ROUTING
  317. 2023-04-13 14:05:32.949001 97.90% [DEBUG] switch_core_state_machine.c:230 sofia/default/17879392883@149.28.206.5 Standard ROUTING
  318. 2023-04-13 14:05:32.949001 97.90% [INFO] mod_dialplan_xml.c:639 Processing V4131405310014682707 <17879392883>->17878071346 in context default
  319. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/16306968030@192.158.239.154 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  320. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/16306968030@192.158.239.154) State REPORTING going to sleep
  321. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/16306968030@192.158.239.154) State Change CS_REPORTING -> CS_DESTROY
  322. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_session.c:1753 Session 15366 (sofia/default/16306968030@192.158.239.154) Locked, Waiting on external entities
  323. 2023-04-13 14:05:32.969066 97.93% [NOTICE] switch_core_session.c:1771 Session 15366 (sofia/default/16306968030@192.158.239.154) Ended
  324. 2023-04-13 14:05:32.969066 97.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/16306968030@192.158.239.154 [CS_DESTROY]
  325. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/16306968030@192.158.239.154) Running State Change CS_DESTROY (Cur 1 Tot 15367)
  326. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/16306968030@192.158.239.154) State DESTROY
  327. 2023-04-13 14:05:32.969066 97.93% [DEBUG] mod_sofia.c:379 sofia/default/16306968030@192.158.239.154 SOFIA DESTROY
  328. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/16306968030@192.158.239.154 Standard DESTROY
  329. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/16306968030@192.158.239.154) State DESTROY going to sleep
  330. 2023-04-13 14:05:32.969066 97.93% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  331. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  332. 2023-04-13 14:05:32.969066 97.93% [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')
  333. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  334. 2023-04-13 14:05:32.969066 97.93% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  335. 2023-04-13 14:05:32.969066 97.93% [WARNING] mod_dialplan_xml.c:669 Context default not found
  336. 2023-04-13 14:05:32.969066 97.93% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  337. 2023-04-13 14:05:32.969066 97.93% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/17879392883@149.28.206.5 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  338. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/17879392883@149.28.206.5) State ROUTING going to sleep
  339. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17879392883@149.28.206.5) Running State Change CS_HANGUP (Cur 1 Tot 15367)
  340. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/17879392883@149.28.206.5) Callstate Change RINGING -> HANGUP
  341. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/17879392883@149.28.206.5) State HANGUP
  342. 2023-04-13 14:05:32.969066 97.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/17879392883@149.28.206.5 hanging up, cause: NO_ROUTE_DESTINATION
  343. 2023-04-13 14:05:32.969066 97.93% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  344. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/17879392883@149.28.206.5 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  345. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/17879392883@149.28.206.5) State HANGUP going to sleep
  346. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/17879392883@149.28.206.5) State Change CS_HANGUP -> CS_REPORTING
  347. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/17879392883@149.28.206.5) Running State Change CS_REPORTING (Cur 1 Tot 15367)
  348. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/17879392883@149.28.206.5) State REPORTING
  349. 2023-04-13 14:05:32.969066 97.93% [INFO] mod_json_cdr.c:271 Process [120d4ff5-9fe2-4f76-849d-501f40414ac2.cdr.json]
  350. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/17879392883@149.28.206.5 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  351. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/17879392883@149.28.206.5) State REPORTING going to sleep
  352. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/17879392883@149.28.206.5) State Change CS_REPORTING -> CS_DESTROY
  353. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_session.c:1753 Session 15367 (sofia/default/17879392883@149.28.206.5) Locked, Waiting on external entities
  354. 2023-04-13 14:05:32.969066 97.93% [NOTICE] switch_core_session.c:1771 Session 15367 (sofia/default/17879392883@149.28.206.5) Ended
  355. 2023-04-13 14:05:32.969066 97.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/17879392883@149.28.206.5 [CS_DESTROY]
  356. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/17879392883@149.28.206.5) Running State Change CS_DESTROY (Cur 0 Tot 15367)
  357. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/17879392883@149.28.206.5) State DESTROY
  358. 2023-04-13 14:05:32.969066 97.93% [DEBUG] mod_sofia.c:379 sofia/default/17879392883@149.28.206.5 SOFIA DESTROY
  359. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/17879392883@149.28.206.5 Standard DESTROY
  360. 2023-04-13 14:05:32.969066 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/17879392883@149.28.206.5) State DESTROY going to sleep
  361. 2023-04-13 14:05:33.189058 97.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/6993237845@148.255.23.75 [045d6068-7a5e-47a2-9f50-7ecaa7a06aa3]
  362. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/6993237845@148.255.23.75) Running State Change CS_NEW (Cur 1 Tot 15368)
  363. 2023-04-13 14:05:33.189058 97.93% [INFO] sofia.c:10462 sofia/default/6993237845@148.255.23.75 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 6f39d118364f208f1944926b0fcaf936@148.255.23.75:5060
  364. 2023-04-13 14:05:33.189058 97.93% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  365. 2023-04-13 14:05:33.189058 97.93% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  366. 2023-04-13 14:05:33.189058 97.93% [DEBUG] sofia.c:7499 Channel sofia/default/6993237845@148.255.23.75 entering state [received][100]
  367. 2023-04-13 14:05:33.189058 97.93% [DEBUG] sofia.c:7509 Remote SDP:
  368. v=0
  369. o=root 898251950 898251950 IN IP4 190.80.168.75
  370. s=Asterisk PBX 13.38.3-vici
  371. c=IN IP4 190.80.168.75
  372. t=0 0
  373. m=audio 17228 RTP/AVP 0 8 101
  374. a=rtpmap:0 PCMU/8000
  375. a=rtpmap:8 PCMA/8000
  376. a=rtpmap:101 telephone-event/8000
  377. a=fmtp:101 0-16
  378. a=ptime:20
  379. a=maxptime:150
  380.  
  381. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  382. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  383. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  384. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  385. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  386. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  387. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  388. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  389. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  390. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/6993237845@148.255.23.75 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  391. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_codec.c:111 sofia/default/6993237845@148.255.23.75 Original read codec set to PCMU:0
  392. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  393. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_media.c:5973 sofia/default/6993237845@148.255.23.75 Set 2833 dtmf send payload to 101 recv payload to 101
  394. 2023-04-13 14:05:33.189058 97.93% [DEBUG] sofia.c:7933 (sofia/default/6993237845@148.255.23.75) State Change CS_NEW -> CS_INIT
  395. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/6993237845@148.255.23.75) State NEW
  396. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/6993237845@148.255.23.75) Running State Change CS_INIT (Cur 1 Tot 15368)
  397. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/6993237845@148.255.23.75) State INIT
  398. 2023-04-13 14:05:33.189058 97.93% [DEBUG] mod_sofia.c:97 sofia/default/6993237845@148.255.23.75 SOFIA INIT
  399. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/6993237845@148.255.23.75 Standard INIT
  400. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/6993237845@148.255.23.75) State Change CS_INIT -> CS_ROUTING
  401. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/6993237845@148.255.23.75) State INIT going to sleep
  402. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/6993237845@148.255.23.75) Running State Change CS_ROUTING (Cur 1 Tot 15368)
  403. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_channel.c:2380 (sofia/default/6993237845@148.255.23.75) Callstate Change DOWN -> RINGING
  404. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/6993237845@148.255.23.75) State ROUTING
  405. 2023-04-13 14:05:33.189058 97.93% [DEBUG] mod_sofia.c:158 sofia/default/6993237845@148.255.23.75 SOFIA ROUTING
  406. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/6993237845@148.255.23.75 Standard ROUTING
  407. 2023-04-13 14:05:33.189058 97.93% [INFO] mod_dialplan_xml.c:639 Processing V4121447320000102126 <9167259011>->51983497690 in context default
  408. 2023-04-13 14:05:33.189058 97.93% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  409. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  410. 2023-04-13 14:05:33.189058 97.93% [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')
  411. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  412. 2023-04-13 14:05:33.189058 97.93% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  413. 2023-04-13 14:05:33.189058 97.93% [WARNING] mod_dialplan_xml.c:669 Context default not found
  414. 2023-04-13 14:05:33.189058 97.93% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  415. 2023-04-13 14:05:33.189058 97.93% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/6993237845@148.255.23.75 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  416. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/6993237845@148.255.23.75) State ROUTING going to sleep
  417. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/6993237845@148.255.23.75) Running State Change CS_HANGUP (Cur 1 Tot 15368)
  418. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/6993237845@148.255.23.75) Callstate Change RINGING -> HANGUP
  419. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/6993237845@148.255.23.75) State HANGUP
  420. 2023-04-13 14:05:33.189058 97.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/6993237845@148.255.23.75 hanging up, cause: NO_ROUTE_DESTINATION
  421. 2023-04-13 14:05:33.189058 97.93% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  422. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/6993237845@148.255.23.75 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  423. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/6993237845@148.255.23.75) State HANGUP going to sleep
  424. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/6993237845@148.255.23.75) State Change CS_HANGUP -> CS_REPORTING
  425. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/6993237845@148.255.23.75) Running State Change CS_REPORTING (Cur 1 Tot 15368)
  426. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/6993237845@148.255.23.75) State REPORTING
  427. 2023-04-13 14:05:33.189058 97.93% [INFO] mod_json_cdr.c:271 Process [045d6068-7a5e-47a2-9f50-7ecaa7a06aa3.cdr.json]
  428. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/6993237845@148.255.23.75 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  429. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/6993237845@148.255.23.75) State REPORTING going to sleep
  430. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/6993237845@148.255.23.75) State Change CS_REPORTING -> CS_DESTROY
  431. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_session.c:1753 Session 15368 (sofia/default/6993237845@148.255.23.75) Locked, Waiting on external entities
  432. 2023-04-13 14:05:33.189058 97.93% [NOTICE] switch_core_session.c:1771 Session 15368 (sofia/default/6993237845@148.255.23.75) Ended
  433. 2023-04-13 14:05:33.189058 97.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/6993237845@148.255.23.75 [CS_DESTROY]
  434. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/6993237845@148.255.23.75) Running State Change CS_DESTROY (Cur 0 Tot 15368)
  435. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/6993237845@148.255.23.75) State DESTROY
  436. 2023-04-13 14:05:33.189058 97.93% [DEBUG] mod_sofia.c:379 sofia/default/6993237845@148.255.23.75 SOFIA DESTROY
  437. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/6993237845@148.255.23.75 Standard DESTROY
  438. 2023-04-13 14:05:33.189058 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/6993237845@148.255.23.75) State DESTROY going to sleep
  439. 2023-04-13 14:05:33.228997 97.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/15616358450@168.235.110.123 [6b0bdab1-ee1f-417f-abd7-30d469113458]
  440. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/15616358450@168.235.110.123) Running State Change CS_NEW (Cur 1 Tot 15369)
  441. 2023-04-13 14:05:33.228997 97.93% [INFO] sofia.c:10462 sofia/default/15616358450@168.235.110.123 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 42b0528f58d8984e47c9f9c12855d804@168.235.110.123:5060
  442. 2023-04-13 14:05:33.228997 97.93% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  443. 2023-04-13 14:05:33.228997 97.93% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  444. 2023-04-13 14:05:33.228997 97.93% [DEBUG] sofia.c:7499 Channel sofia/default/15616358450@168.235.110.123 entering state [received][100]
  445. 2023-04-13 14:05:33.228997 97.93% [DEBUG] sofia.c:7509 Remote SDP:
  446. v=0
  447. o=root 2105228367 2105228367 IN IP4 168.235.110.123
  448. s=Asterisk PBX 11.25.1-vici
  449. c=IN IP4 168.235.110.123
  450. t=0 0
  451. m=audio 16686 RTP/AVP 0 8 18 101
  452. a=rtpmap:0 PCMU/8000
  453. a=rtpmap:8 PCMA/8000
  454. a=rtpmap:18 G729/8000
  455. a=fmtp:18 annexb=no
  456. a=rtpmap:101 telephone-event/8000
  457. a=fmtp:101 0-16
  458. a=ptime:20
  459.  
  460. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  461. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  462. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  463. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  464. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  465. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  466. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  467. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  468. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  469. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  470. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  471. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  472. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  473. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/15616358450@168.235.110.123 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  474. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_codec.c:111 sofia/default/15616358450@168.235.110.123 Original read codec set to PCMU:0
  475. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  476. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_media.c:5973 sofia/default/15616358450@168.235.110.123 Set 2833 dtmf send payload to 101 recv payload to 101
  477. 2023-04-13 14:05:33.228997 97.93% [DEBUG] sofia.c:7933 (sofia/default/15616358450@168.235.110.123) State Change CS_NEW -> CS_INIT
  478. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/15616358450@168.235.110.123) State NEW
  479. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/15616358450@168.235.110.123) Running State Change CS_INIT (Cur 1 Tot 15369)
  480. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/15616358450@168.235.110.123) State INIT
  481. 2023-04-13 14:05:33.228997 97.93% [DEBUG] mod_sofia.c:97 sofia/default/15616358450@168.235.110.123 SOFIA INIT
  482. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/15616358450@168.235.110.123 Standard INIT
  483. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/15616358450@168.235.110.123) State Change CS_INIT -> CS_ROUTING
  484. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/15616358450@168.235.110.123) State INIT going to sleep
  485. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/15616358450@168.235.110.123) Running State Change CS_ROUTING (Cur 1 Tot 15369)
  486. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_channel.c:2380 (sofia/default/15616358450@168.235.110.123) Callstate Change DOWN -> RINGING
  487. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/15616358450@168.235.110.123) State ROUTING
  488. 2023-04-13 14:05:33.228997 97.93% [DEBUG] mod_sofia.c:158 sofia/default/15616358450@168.235.110.123 SOFIA ROUTING
  489. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/15616358450@168.235.110.123 Standard ROUTING
  490. 2023-04-13 14:05:33.228997 97.93% [INFO] mod_dialplan_xml.c:639 Processing V4131405290000461810 <15616358450>->15616541645 in context default
  491. 2023-04-13 14:05:33.228997 97.93% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  492. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  493. 2023-04-13 14:05:33.228997 97.93% [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')
  494. 2023-04-13 14:05:33.228997 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  495. 2023-04-13 14:05:33.228997 97.93% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  496. 2023-04-13 14:05:33.228997 97.93% [WARNING] mod_dialplan_xml.c:669 Context default not found
  497. 2023-04-13 14:05:33.228997 97.93% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  498. 2023-04-13 14:05:33.228997 97.93% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/15616358450@168.235.110.123 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  499. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/15616358450@168.235.110.123) State ROUTING going to sleep
  500. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/15616358450@168.235.110.123) Running State Change CS_HANGUP (Cur 1 Tot 15369)
  501. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/15616358450@168.235.110.123) Callstate Change RINGING -> HANGUP
  502. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/15616358450@168.235.110.123) State HANGUP
  503. 2023-04-13 14:05:33.249024 97.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/15616358450@168.235.110.123 hanging up, cause: NO_ROUTE_DESTINATION
  504. 2023-04-13 14:05:33.249024 97.93% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  505. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/15616358450@168.235.110.123 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  506. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/15616358450@168.235.110.123) State HANGUP going to sleep
  507. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/15616358450@168.235.110.123) State Change CS_HANGUP -> CS_REPORTING
  508. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/15616358450@168.235.110.123) Running State Change CS_REPORTING (Cur 1 Tot 15369)
  509. 2023-04-13 14:05:33.249024 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/15616358450@168.235.110.123) State REPORTING
  510. 2023-04-13 14:05:33.249024 97.93% [INFO] mod_json_cdr.c:271 Process [6b0bdab1-ee1f-417f-abd7-30d469113458.cdr.json]
  511. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/15616358450@168.235.110.123 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  512. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/15616358450@168.235.110.123) State REPORTING going to sleep
  513. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/15616358450@168.235.110.123) State Change CS_REPORTING -> CS_DESTROY
  514. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_session.c:1753 Session 15369 (sofia/default/15616358450@168.235.110.123) Locked, Waiting on external entities
  515. 2023-04-13 14:05:33.269027 97.93% [NOTICE] switch_core_session.c:1771 Session 15369 (sofia/default/15616358450@168.235.110.123) Ended
  516. 2023-04-13 14:05:33.269027 97.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/15616358450@168.235.110.123 [CS_DESTROY]
  517. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/15616358450@168.235.110.123) Running State Change CS_DESTROY (Cur 0 Tot 15369)
  518. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/15616358450@168.235.110.123) State DESTROY
  519. 2023-04-13 14:05:33.269027 97.93% [DEBUG] mod_sofia.c:379 sofia/default/15616358450@168.235.110.123 SOFIA DESTROY
  520. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/15616358450@168.235.110.123 Standard DESTROY
  521. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/15616358450@168.235.110.123) State DESTROY going to sleep
  522. 2023-04-13 14:05:33.269027 97.93% [NOTICE] switch_channel.c:1123 New Channel sofia/default/16302669076@192.158.239.154 [90035199-11cd-48b1-9836-3f640f0be75c]
  523. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16302669076@192.158.239.154) Running State Change CS_NEW (Cur 1 Tot 15370)
  524. 2023-04-13 14:05:33.269027 97.93% [INFO] sofia.c:10462 sofia/default/16302669076@192.158.239.154 receiving invite from 199.167.144.226:5060 version: 1.10.7 -release 64bit call-id: 160984af26c1087d1637b3147a4b8f6c@192.158.239.154:5060
  525. 2023-04-13 14:05:33.269027 97.93% [DEBUG] sofia.c:10556 verifying acl "default" for ip/port 199.167.144.226:0.
  526. 2023-04-13 14:05:33.269027 97.93% [DEBUG] sofia.c:10585 IP 199.167.144.226 Approved by acl "default[]". Access Granted.
  527. 2023-04-13 14:05:33.269027 97.93% [DEBUG] sofia.c:7499 Channel sofia/default/16302669076@192.158.239.154 entering state [received][100]
  528. 2023-04-13 14:05:33.269027 97.93% [DEBUG] sofia.c:7509 Remote SDP:
  529. v=0
  530. o=root 1240862897 1240862897 IN IP4 192.158.239.154
  531. s=Asterisk PBX 13.29.2-vici
  532. c=IN IP4 192.158.239.154
  533. t=0 0
  534. m=audio 17884 RTP/AVP 0 8 18 101
  535. a=rtpmap:0 PCMU/8000
  536. a=rtpmap:8 PCMA/8000
  537. a=rtpmap:18 G729/8000
  538. a=fmtp:18 annexb=no
  539. a=rtpmap:101 telephone-event/8000
  540. a=fmtp:101 0-16
  541. a=ptime:20
  542. a=maxptime:150
  543.  
  544. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  545. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  546. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  547. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  548. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  549. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  550. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  551. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  552. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  553. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  554. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5650 Audio Codec Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
  555. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5705 Audio Codec Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
  556. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5566 Set telephone-event payload to 101@8000
  557. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:3870 Set Codec sofia/default/16302669076@192.158.239.154 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  558. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_codec.c:111 sofia/default/16302669076@192.158.239.154 Original read codec set to PCMU:0
  559. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5915 Set telephone-event payload to 101@8000
  560. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_media.c:5973 sofia/default/16302669076@192.158.239.154 Set 2833 dtmf send payload to 101 recv payload to 101
  561. 2023-04-13 14:05:33.269027 97.93% [DEBUG] sofia.c:7933 (sofia/default/16302669076@192.158.239.154) State Change CS_NEW -> CS_INIT
  562. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:600 (sofia/default/16302669076@192.158.239.154) State NEW
  563. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16302669076@192.158.239.154) Running State Change CS_INIT (Cur 1 Tot 15370)
  564. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/16302669076@192.158.239.154) State INIT
  565. 2023-04-13 14:05:33.269027 97.93% [DEBUG] mod_sofia.c:97 sofia/default/16302669076@192.158.239.154 SOFIA INIT
  566. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:40 sofia/default/16302669076@192.158.239.154 Standard INIT
  567. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:48 (sofia/default/16302669076@192.158.239.154) State Change CS_INIT -> CS_ROUTING
  568. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:624 (sofia/default/16302669076@192.158.239.154) State INIT going to sleep
  569. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16302669076@192.158.239.154) Running State Change CS_ROUTING (Cur 1 Tot 15370)
  570. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_channel.c:2380 (sofia/default/16302669076@192.158.239.154) Callstate Change DOWN -> RINGING
  571. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/16302669076@192.158.239.154) State ROUTING
  572. 2023-04-13 14:05:33.269027 97.93% [DEBUG] mod_sofia.c:158 sofia/default/16302669076@192.158.239.154 SOFIA ROUTING
  573. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_core_state_machine.c:230 sofia/default/16302669076@192.158.239.154 Standard ROUTING
  574. 2023-04-13 14:05:33.269027 97.93% [INFO] mod_dialplan_xml.c:639 Processing V4131405310003798145 <16302669076>->14859345516304530762 in context default
  575. 2023-04-13 14:05:33.269027 97.93% [INFO] switch_cpp.cpp:1465 [ASTPP] CACHE FAIL
  576. 2023-04-13 14:05:33.269027 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [CACHING_DEBUG_SYSTEM]1.0
  577. 2023-04-13 14:05:33.269027 97.93% [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')
  578. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_cpp.cpp:1465 [ASTPP] [LOAD_ADDON_CONF] Query :SELECT package_name FROM addons
  579. 2023-04-13 14:05:33.288999 97.93% [ERR] switch_cpp.cpp:439 Trying to getHeader an invalid header!
  580. 2023-04-13 14:05:33.288999 97.93% [WARNING] mod_dialplan_xml.c:669 Context default not found
  581. 2023-04-13 14:05:33.288999 97.93% [INFO] switch_core_state_machine.c:306 No Route, Aborting
  582. 2023-04-13 14:05:33.288999 97.93% [NOTICE] switch_core_state_machine.c:307 Hangup sofia/default/16302669076@192.158.239.154 [CS_ROUTING] [NO_ROUTE_DESTINATION]
  583. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:640 (sofia/default/16302669076@192.158.239.154) State ROUTING going to sleep
  584. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16302669076@192.158.239.154) Running State Change CS_HANGUP (Cur 1 Tot 15370)
  585. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:844 (sofia/default/16302669076@192.158.239.154) Callstate Change RINGING -> HANGUP
  586. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/16302669076@192.158.239.154) State HANGUP
  587. 2023-04-13 14:05:33.288999 97.93% [DEBUG] mod_sofia.c:468 Channel sofia/default/16302669076@192.158.239.154 hanging up, cause: NO_ROUTE_DESTINATION
  588. 2023-04-13 14:05:33.288999 97.93% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 404
  589. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:59 sofia/default/16302669076@192.158.239.154 Standard HANGUP, cause: NO_ROUTE_DESTINATION
  590. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:846 (sofia/default/16302669076@192.158.239.154) State HANGUP going to sleep
  591. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:616 (sofia/default/16302669076@192.158.239.154) State Change CS_HANGUP -> CS_REPORTING
  592. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:581 (sofia/default/16302669076@192.158.239.154) Running State Change CS_REPORTING (Cur 1 Tot 15370)
  593. 2023-04-13 14:05:33.288999 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/16302669076@192.158.239.154) State REPORTING
  594. 2023-04-13 14:05:33.288999 97.93% [INFO] mod_json_cdr.c:271 Process [90035199-11cd-48b1-9836-3f640f0be75c.cdr.json]
  595. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:168 sofia/default/16302669076@192.158.239.154 Standard REPORTING, cause: NO_ROUTE_DESTINATION
  596. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:932 (sofia/default/16302669076@192.158.239.154) State REPORTING going to sleep
  597. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:607 (sofia/default/16302669076@192.158.239.154) State Change CS_REPORTING -> CS_DESTROY
  598. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_session.c:1753 Session 15370 (sofia/default/16302669076@192.158.239.154) Locked, Waiting on external entities
  599. 2023-04-13 14:05:33.309027 97.93% [NOTICE] switch_core_session.c:1771 Session 15370 (sofia/default/16302669076@192.158.239.154) Ended
  600. 2023-04-13 14:05:33.309027 97.93% [NOTICE] switch_core_session.c:1775 Close Channel sofia/default/16302669076@192.158.239.154 [CS_DESTROY]
  601. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:735 (sofia/default/16302669076@192.158.239.154) Running State Change CS_DESTROY (Cur 0 Tot 15370)
  602. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/16302669076@192.158.239.154) State DESTROY
  603. 2023-04-13 14:05:33.309027 97.93% [DEBUG] mod_sofia.c:379 sofia/default/16302669076@192.158.239.154 SOFIA DESTROY
  604. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:175 sofia/default/16302669076@192.158.239.154 Standard DESTROY
  605. 2023-04-13 14:05:33.309027 97.93% [DEBUG] switch_core_state_machine.c:745 (sofia/default/16302669076@192.158.239.154) State DESTROY going to sleep
  606.