- 2024-07-29 09:17:01.606233 96.47% [INFO] switch_time.c:627 Clock synchronized to system time.
- 2024-07-29 09:17:06.986233 96.40% [NOTICE] switch_channel.c:1123 New Channel sofia/external/trunk@216.242.131.104 [4148b89c-1b1c-4285-9784-07d9db42a98a]
- 2024-07-29 09:17:06.986233 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_NEW (Cur 1 Tot 3)
- 2024-07-29 09:17:06.986233 96.40% [INFO] sofia.c:10453 sofia/external/trunk@216.242.131.104 receiving invite from 216.242.131.104:5060 version: 1.10.9-release 64bit call-id: 1278969688-1885822372-1639957540
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] sofia.c:7487 Channel sofia/external/trunk@216.242.131.104 entering state [received][100]
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] sofia.c:7497 Remote SDP:
- v=0
- o=trunk 16264 18299 IN IP4 192.168.1.83
- s=call
- c=IN IP4 192.168.1.83
- t=0 0
- m=audio 25282 RTP/AVP 0 101
- a=rtpmap:0 pcmu/8000
- a=rtpmap:101 telephone-event/8000
- a=fmtp:101 0-11
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] sofia.c:7900 (sofia/external/trunk@216.242.131.104) State Change CS_NEW -> CS_INIT
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:600 (sofia/external/trunk@216.242.131.104) State NEW
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_INIT (Cur 1 Tot 3)
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:624 (sofia/external/trunk@216.242.131.104) State INIT
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] mod_sofia.c:97 sofia/external/trunk@216.242.131.104 SOFIA INIT
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:40 sofia/external/trunk@216.242.131.104 Standard INIT
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:48 (sofia/external/trunk@216.242.131.104) State Change CS_INIT -> CS_ROUTING
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:624 (sofia/external/trunk@216.242.131.104) State INIT going to sleep
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_ROUTING (Cur 1 Tot 3)
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_channel.c:2380 (sofia/external/trunk@216.242.131.104) Callstate Change DOWN -> RINGING
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:640 (sofia/external/trunk@216.242.131.104) State ROUTING
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] mod_sofia.c:158 sofia/external/trunk@216.242.131.104 SOFIA ROUTING
- 2024-07-29 09:17:07.006240 96.40% [DEBUG] switch_core_state_machine.c:230 sofia/external/trunk@216.242.131.104 Standard ROUTING
- 2024-07-29 09:17:07.026228 96.40% [INFO] mod_dialplan_xml.c:639 Processing trunk <trunk>->70112039450016 in context public
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->unloop] continue=false
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->outside_call] continue=true
- Dialplan: sofia/external/trunk@216.242.131.104 Absolute Condition [outside_call]
- Dialplan: sofia/external/trunk@216.242.131.104 Action set(outside_call=true)
- Dialplan: sofia/external/trunk@216.242.131.104 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->bbb_dialin authorised and arrived from other server] continue=false
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (FAIL) [bbb_dialin authorised and arrived from other server] destination_number(70112039450016) =~ /^\*321(.*)$/ break=on-false
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->bbb_dialin auth checking] continue=false
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (FAIL) [bbb_dialin auth checking] destination_number(70112039450016) =~ /^(\+1|\+91)?(\d{10,12})$/ break=on-false
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->bbb_webrtc_call] continue=true
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (FAIL) [bbb_webrtc_call] ${sip_user_agent}(VoIP01) =~ /bbb-webrtc-sfu/ break=on-false
- Dialplan: sofia/external/trunk@216.242.131.104 parsing [public->bbb_webrtc_call] continue=true
- Dialplan: sofia/external/trunk@216.242.131.104 Regex (FAIL) [bbb_webrtc_call] ${sip_via_protocol}(udp) =~ /^wss?$/ break=on-false
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:281 (sofia/external/trunk@216.242.131.104) State Change CS_ROUTING -> CS_EXECUTE
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:640 (sofia/external/trunk@216.242.131.104) State ROUTING going to sleep
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_EXECUTE (Cur 1 Tot 3)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:647 (sofia/external/trunk@216.242.131.104) State EXECUTE
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] mod_sofia.c:213 sofia/external/trunk@216.242.131.104 SOFIA EXECUTE
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:323 sofia/external/trunk@216.242.131.104 Standard EXECUTE
- EXECUTE [depth=0] sofia/external/trunk@216.242.131.104 set(outside_call=true)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] mod_dptools.c:1673 SET sofia/external/trunk@216.242.131.104 [outside_call]=[true]
- EXECUTE [depth=0] sofia/external/trunk@216.242.131.104 export(RFC2822_DATE=Mon, 29 Jul 2024 09:17:07 +0000)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 29 Jul 2024 09:17:07 +0000]
- 2024-07-29 09:17:07.047063 96.40% [NOTICE] switch_core_state_machine.c:382 sofia/external/trunk@216.242.131.104 has executed the last dialplan instruction, hanging up.
- 2024-07-29 09:17:07.047063 96.40% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/external/trunk@216.242.131.104 [CS_EXECUTE] [NORMAL_CLEARING]
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:647 (sofia/external/trunk@216.242.131.104) State EXECUTE going to sleep
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_HANGUP (Cur 1 Tot 3)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:844 (sofia/external/trunk@216.242.131.104) Callstate Change RINGING -> HANGUP
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:846 (sofia/external/trunk@216.242.131.104) State HANGUP
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] mod_sofia.c:468 Channel sofia/external/trunk@216.242.131.104 hanging up, cause: NORMAL_CLEARING
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] mod_sofia.c:613 Responding to INVITE with: 480
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:59 sofia/external/trunk@216.242.131.104 Standard HANGUP, cause: NORMAL_CLEARING
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:846 (sofia/external/trunk@216.242.131.104) State HANGUP going to sleep
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:616 (sofia/external/trunk@216.242.131.104) State Change CS_HANGUP -> CS_REPORTING
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:581 (sofia/external/trunk@216.242.131.104) Running State Change CS_REPORTING (Cur 1 Tot 3)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:932 (sofia/external/trunk@216.242.131.104) State REPORTING
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:168 sofia/external/trunk@216.242.131.104 Standard REPORTING, cause: NORMAL_CLEARING
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:932 (sofia/external/trunk@216.242.131.104) State REPORTING going to sleep
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:607 (sofia/external/trunk@216.242.131.104) State Change CS_REPORTING -> CS_DESTROY
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_session.c:1743 Session 3 (sofia/external/trunk@216.242.131.104) Locked, Waiting on external entities
- 2024-07-29 09:17:07.047063 96.40% [NOTICE] switch_core_session.c:1761 Session 3 (sofia/external/trunk@216.242.131.104) Ended
- 2024-07-29 09:17:07.047063 96.40% [NOTICE] switch_core_session.c:1765 Close Channel sofia/external/trunk@216.242.131.104 [CS_DESTROY]
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:735 (sofia/external/trunk@216.242.131.104) Running State Change CS_DESTROY (Cur 0 Tot 3)
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:745 (sofia/external/trunk@216.242.131.104) State DESTROY
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] mod_sofia.c:379 sofia/external/trunk@216.242.131.104 SOFIA DESTROY
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:175 sofia/external/trunk@216.242.131.104 Standard DESTROY
- 2024-07-29 09:17:07.047063 96.40% [DEBUG] switch_core_state_machine.c:745 (sofia/external/trunk@216.242.131.104) State DESTROY going to sleep
- freeswitch@bbb01stage> reloadacl