1. 2024-07-31 11:54:51.087957 93.53% [NOTICE] switch_channel.c:1123 New Channel sofia/external/bbbuser@44.222.41.176:5060 [ac0d5218-607f-4f3d-afe7-c112840c056b]
2. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_NEW (Cur 1 Tot 10)
3. 2024-07-31 11:54:51.087957 93.53% [INFO] sofia.c:10453 sofia/external/bbbuser@44.222.41.176:5060 receiving invite from 103.254.35.50:56027 version: 1.10.9-release  64bit call-id: XBA_UCHl2zuihN7Q4YKhMg..
4. 2024-07-31 11:54:51.087957 93.53% [DEBUG] sofia.c:7487 Channel sofia/external/bbbuser@44.222.41.176:5060 entering state [received][100]
5. 2024-07-31 11:54:51.087957 93.53% [DEBUG] sofia.c:7497 Remote SDP:
6. v=0
7. o=Z 0 29851821 IN IP4 103.254.35.50
8. s=Z
9. c=IN IP4 103.254.35.50
10. t=0 0
11. m=audio 47561 RTP/AVP 106 9 98 101 0 8 3
12. a=rtpmap:106 opus/48000/2
13. a=fmtp:106 sprop-maxcapturerate=16000; minptime=20; useinbandfec=1
14. a=rtpmap:98 telephone-event/48000
15. a=fmtp:98 0-16
16. a=rtpmap:101 telephone-event/8000
17. a=fmtp:101 0-16
18. a=rtcp-mux
19.  
20. 2024-07-31 11:54:51.087957 93.53% [DEBUG] sofia.c:7900 (sofia/external/bbbuser@44.222.41.176:5060) State Change CS_NEW -> CS_INIT
21. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:600 (sofia/external/bbbuser@44.222.41.176:5060) State NEW
22. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_INIT (Cur 1 Tot 10)
23. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/bbbuser@44.222.41.176:5060) State INIT
24. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:97 sofia/external/bbbuser@44.222.41.176:5060 SOFIA INIT
25. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:40 sofia/external/bbbuser@44.222.41.176:5060 Standard INIT
26. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:48 (sofia/external/bbbuser@44.222.41.176:5060) State Change CS_INIT -> CS_ROUTING
27. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:624 (sofia/external/bbbuser@44.222.41.176:5060) State INIT going to sleep
28. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_ROUTING (Cur 1 Tot 10)
29. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_channel.c:2380 (sofia/external/bbbuser@44.222.41.176:5060) Callstate Change DOWN -> RINGING
30. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/bbbuser@44.222.41.176:5060) State ROUTING
31. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:158 sofia/external/bbbuser@44.222.41.176:5060 SOFIA ROUTING
32. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:230 sofia/external/bbbuser@44.222.41.176:5060 Standard ROUTING
33. 2024-07-31 11:54:51.087957 93.53% [INFO] mod_dialplan_xml.c:639 Processing bbbuser <bbbuser>->*1255612755500 in context public
34. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->unloop] continue=false
35. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
36. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
37. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->outside_call] continue=true
38. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Absolute Condition [outside_call]
39. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Action set(outside_call=true)
40. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
41. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->bbb_dialin authorised and arrived from other server] continue=false
42. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (FAIL) [bbb_dialin authorised and arrived from other server] destination_number(*1255612755500) =~ /^\*321(.*)$/ break=on-false
43. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->bbb_dialin auth checking] continue=false
44. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (FAIL) [bbb_dialin auth checking] destination_number(*1255612755500) =~ /^(\+1|\+91)?(\d{10,12})$/ break=on-false
45. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->bbb_webrtc_call] continue=true
46. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (FAIL) [bbb_webrtc_call] ${sip_user_agent}(Z 5.6.2 v2.10.20.2) =~ /bbb-webrtc-sfu/ break=on-false
47. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 parsing [public->bbb_webrtc_call] continue=true
48. Dialplan: sofia/external/bbbuser@44.222.41.176:5060 Regex (FAIL) [bbb_webrtc_call] ${sip_via_protocol}(udp) =~ /^wss?$/ break=on-false
49. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:281 (sofia/external/bbbuser@44.222.41.176:5060) State Change CS_ROUTING -> CS_EXECUTE
50. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:640 (sofia/external/bbbuser@44.222.41.176:5060) State ROUTING going to sleep
51. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_EXECUTE (Cur 1 Tot 10)
52. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:647 (sofia/external/bbbuser@44.222.41.176:5060) State EXECUTE
53. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:213 sofia/external/bbbuser@44.222.41.176:5060 SOFIA EXECUTE
54. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:323 sofia/external/bbbuser@44.222.41.176:5060 Standard EXECUTE
55. EXECUTE [depth=0] sofia/external/bbbuser@44.222.41.176:5060 set(outside_call=true)
56. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_dptools.c:1673 SET sofia/external/bbbuser@44.222.41.176:5060 [outside_call]=[true]
57. EXECUTE [depth=0] sofia/external/bbbuser@44.222.41.176:5060 export(RFC2822_DATE=Wed, 31 Jul 2024 11:54:51 +0000)
58. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 31 Jul 2024 11:54:51 +0000]
59. 2024-07-31 11:54:51.087957 93.53% [NOTICE] switch_core_state_machine.c:382 sofia/external/bbbuser@44.222.41.176:5060 has executed the last dialplan instruction, hanging up.
60. 2024-07-31 11:54:51.087957 93.53% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/external/bbbuser@44.222.41.176:5060 [CS_EXECUTE] [NORMAL_CLEARING]
61. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:647 (sofia/external/bbbuser@44.222.41.176:5060) State EXECUTE going to sleep
62. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_HANGUP (Cur 1 Tot 10)
63. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:844 (sofia/external/bbbuser@44.222.41.176:5060) Callstate Change RINGING -> HANGUP
64. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:846 (sofia/external/bbbuser@44.222.41.176:5060) State HANGUP
65. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:468 Channel sofia/external/bbbuser@44.222.41.176:5060 hanging up, cause: NORMAL_CLEARING
66. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 480
67. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:59 sofia/external/bbbuser@44.222.41.176:5060 Standard HANGUP, cause: NORMAL_CLEARING
68. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:846 (sofia/external/bbbuser@44.222.41.176:5060) State HANGUP going to sleep
69. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:616 (sofia/external/bbbuser@44.222.41.176:5060) State Change CS_HANGUP -> CS_REPORTING
70. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:581 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_REPORTING (Cur 1 Tot 10)
71. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:932 (sofia/external/bbbuser@44.222.41.176:5060) State REPORTING
72. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:168 sofia/external/bbbuser@44.222.41.176:5060 Standard REPORTING, cause: NORMAL_CLEARING
73. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:932 (sofia/external/bbbuser@44.222.41.176:5060) State REPORTING going to sleep
74. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:607 (sofia/external/bbbuser@44.222.41.176:5060) State Change CS_REPORTING -> CS_DESTROY
75. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_session.c:1743 Session 10 (sofia/external/bbbuser@44.222.41.176:5060) Locked, Waiting on external entities
76. 2024-07-31 11:54:51.087957 93.53% [NOTICE] switch_core_session.c:1761 Session 10 (sofia/external/bbbuser@44.222.41.176:5060) Ended
77. 2024-07-31 11:54:51.087957 93.53% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/bbbuser@44.222.41.176:5060 [CS_DESTROY]
78. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:735 (sofia/external/bbbuser@44.222.41.176:5060) Running State Change CS_DESTROY (Cur 0 Tot 10)
79. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:745 (sofia/external/bbbuser@44.222.41.176:5060) State DESTROY
80. 2024-07-31 11:54:51.087957 93.53% [DEBUG] mod_sofia.c:379 sofia/external/bbbuser@44.222.41.176:5060 SOFIA DESTROY
81. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:175 sofia/external/bbbuser@44.222.41.176:5060 Standard DESTROY
82. 2024-07-31 11:54:51.087957 93.53% [DEBUG] switch_core_state_machine.c:745 (sofia/external/bbbuser@44.222.41.176:5060) State DESTROY going to sleep
83.