SIP PBX integration
- Thread starter Win
- Start date
- Status
<action application="bridge" data="sofia/internal/101@1.2.3.4"/>
https://developer.signalwire.com/freeswitch/FreeSWITCH-Explained/Modules/mod-dptools/6586484/
https://developer.signalwire.com/freeswitch/FreeSWITCH-Explained/Modules/mod-dptools/6586484/
I have tried as suggested but not working, the call dropped
the use case:
FusionPBX on 1.1.1.1
ext 101 calls ext105@1.2.3.4 (yealink), by dialing 999105
looks like I missed something:
<extension name="to-yealink" continue="false" uuid="1e4291b">
<condition field="destination_number" expression="^999(\d{3})$">
<action application="set" data="call_direction=external" inline="true"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=true"/>
<action application="set" data="callee_id_number=1003$1"/>
<action application="bridge" data="sofia/internal/1003$1@1.2.3.4"/>
</condition>
</extension>
== log ==
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/101@pbx.example.co.id [c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_NEW (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [INFO] sofia.c:10459 sofia/internal/101@pbx.example.co.id receiving invite from 149.1.1.1:45090 version: 1.10.11 -release 64bit call-id: f976bb3396cc49b4b5c9e3034efe3668
2024-02-23 16:21:33.711810 98.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 149.1.1.1:0.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/101@pbx.example.co.id) State NEW
2024-02-23 16:21:33.711810 98.97% [DEBUG] sofia.c:2419 detaching session c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
2024-02-23 16:21:33.771811 98.97% [DEBUG] sofia.c:2532 Re-attaching to session c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [INFO] sofia.c:10459 sofia/internal/101@pbx.example.co.id receiving invite from 149.1.1.1:45090 version: 1.10.11 -release 64bit call-id: f976bb3396cc49b4b5c9e3034efe3668
2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 149.1.1.1:0.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/101@pbx.example.co.id entering state [received][100]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7503 Remote SDP:
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb v=0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb o=- 3917694094 3917694094 IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb s=pjmedia
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb b=AS:84
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb t=0 0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=X-nat:0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb m=audio 4002 RTP/AVP 8 0 101
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb c=IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb b=TIAS:64000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:8 PCMA/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:0 PCMU/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:101 telephone-event/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=fmtp:101 0-16
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtcp:4003 IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=ssrc:1933863199 cname:0c5577877d115880
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7906 (sofia/internal/101@pbx.example.co.id) State Change CS_NEW -> CS_INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_INIT (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/101@pbx.example.co.id) State INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:97 sofia/internal/101@pbx.example.co.id SOFIA INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:40 sofia/internal/101@pbx.example.co.id Standard INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/101@pbx.example.co.id) State Change CS_INIT -> CS_ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/101@pbx.example.co.id) State INIT going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_ROUTING (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:2399 (sofia/internal/101@pbx.example.co.id) Callstate Change DOWN -> RINGING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/101@pbx.example.co.id) State ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:158 sofia/internal/101@pbx.example.co.id SOFIA ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:230 sofia/internal/101@pbx.example.co.id Standard ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [INFO] mod_dialplan_xml.c:639 Processing 101 <101>->999105 in context pbx.example.co.id
2024-02-23 16:21:33.791810 98.97% [NOTICE] switch_cpp.cpp:1466 [xml_handler] multiple key:dialplan
bx.example.co.id
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->to-pbx.example.co.id] continue=false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->to-cc.example.co.id] continue=false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [to-cc.example.co.id] destination_number(999105) =~ /^999(\d{3})$/ break=on-false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(call_direction=external) INLINE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(call_direction=external)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [call_direction]=[external]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(hangup_after_bridge=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(continue_on_fail=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(callee_id_number=1003105)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action bridge(sofia/internal/1003105@147.1.1.1)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/101@pbx.example.co.id) State Change CS_ROUTING -> CS_EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/101@pbx.example.co.id) State ROUTING going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_EXECUTE (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@pbx.example.co.id) State EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:213 sofia/internal/101@pbx.example.co.id SOFIA EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:323 sofia/internal/101@pbx.example.co.id Standard EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(hangup_after_bridge=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [hangup_after_bridge]=[true]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(continue_on_fail=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [continue_on_fail]=[true]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(callee_id_number=1003105)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [callee_id_number]=[1003105]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id bridge(sofia/internal/1003105@147.1.1.1)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:1288 sofia/internal/101@pbx.example.co.id EXPORTING[export_vars] [domain_name]=[pbx.example.co.id] to event
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:1288 sofia/internal/101@pbx.example.co.id EXPORTING[export_vars] [domain_uuid]=[77527d50-4d75-4756-9b39-f4298ffc455c] to event
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/1003105@147.1.1.1 [c6388e90-cae0-4a20-83d7-6de88e48fdc4]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:5110 (sofia/internal/1003105@147.1.1.1) State Change CS_NEW -> CS_INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_INIT (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1003105@147.1.1.1) State INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:97 sofia/internal/1003105@147.1.1.1 SOFIA INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [INFO] sofia_glue.c:1659 sofia/internal/1003105@147.1.1.1 sending invite call-id: (null)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia_glue.c:1662 sofia/internal/1003105@147.1.1.1 sending invite version: 1.10.11 -release 64bit
c6388e90-cae0-4a20-83d7-6de88e48fdc4 Local SDP:
c6388e90-cae0-4a20-83d7-6de88e48fdc4 v=0
c6388e90-cae0-4a20-83d7-6de88e48fdc4 o=FreeSWITCH 1708655549 1708655550 IN IP4 149.129.248.234
c6388e90-cae0-4a20-83d7-6de88e48fdc4 s=FreeSWITCH
c6388e90-cae0-4a20-83d7-6de88e48fdc4 c=IN IP4 149.129.248.234
c6388e90-cae0-4a20-83d7-6de88e48fdc4 t=0 0
c6388e90-cae0-4a20-83d7-6de88e48fdc4 m=audio 24544 RTP/AVP 8 0 9 101 13
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:8 PCMA/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:0 PCMU/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:9 G722/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:101 telephone-event/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=fmtp:101 0-15
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:13 CN/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=ptime:20
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=sendrecv
c6388e90-cae0-4a20-83d7-6de88e48fdc4
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003105@147.1.1.1 Standard INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003105@147.1.1.1) State Change CS_INIT -> CS_ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1003105@147.1.1.1) State INIT going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_ROUTING (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/1003105@147.1.1.1 entering state [calling][0]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1003105@147.1.1.1) State ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:158 sofia/internal/1003105@147.1.1.1 SOFIA ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1003105@147.1.1.1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1003105@147.1.1.1) State ROUTING going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1003105@147.1.1.1) State CONSUME_MEDIA
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1003105@147.1.1.1) State CONSUME_MEDIA going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] sofia.c:6672 Remote Reason: 16
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/1003105@147.1.1.1 entering state [terminated][480]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] sofia.c:8735 Hangup sofia/internal/1003105@147.1.1.1 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_HANGUP (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/1003105@147.1.1.1) Callstate Change DOWN -> HANGUP
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1003105@147.1.1.1) State HANGUP
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] mod_sofia.c:469 Channel sofia/internal/1003105@147.1.1.1 hanging up, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:59 sofia/internal/1003105@147.1.1.1 Standard HANGUP, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1003105@147.1.1.1) State HANGUP going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/1003105@147.1.1.1) State Change CS_HANGUP -> CS_REPORTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_REPORTING (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1003105@147.1.1.1) State REPORTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:168 sofia/internal/1003105@147.1.1.1 Standard REPORTING, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1003105@147.1.1.1) State REPORTING going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/1003105@147.1.1.1) State Change CS_REPORTING -> CS_DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_session.c:1744 Session 4 (sofia/internal/1003105@147.1.1.1) Locked, Waiting on external entities
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_ivr_originate.c:4056 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] switch_core_session.c:1762 Session 4 (sofia/internal/1003105@147.1.1.1) Ended
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/1003105@147.1.1.1 [CS_DESTROY]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1003105@147.1.1.1) Running State Change CS_DESTROY (Cur 1 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1003105@147.1.1.1) State DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] mod_sofia.c:380 sofia/internal/1003105@147.1.1.1 SOFIA DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:175 sofia/internal/1003105@147.1.1.1 Standard DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1003105@147.1.1.1) State DESTROY going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [INFO] mod_dptools.c:3635 Originate Failed. Cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_state_machine.c:382 sofia/internal/101@pbx.example.co.id has executed the last dialplan instruction, hanging up.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/101@pbx.example.co.id [CS_EXECUTE] [NORMAL_CLEARING]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@pbx.example.co.id) State EXECUTE going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_HANGUP (Cur 1 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/101@pbx.example.co.id) Callstate Change RINGING -> HANGUP
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@pbx.example.co.id) State HANGUP
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:463 sofia/internal/101@pbx.example.co.id Overriding SIP cause 480 with 480 from the other leg
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:469 Channel sofia/internal/101@pbx.example.co.id hanging up, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 480
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:59 sofia/internal/101@pbx.example.co.id Standard HANGUP, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@pbx.example.co.id) State HANGUP going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/101@pbx.example.co.id) State Change CS_HANGUP -> CS_REPORTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_REPORTING (Cur 1 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@pbx.example.co.id) State REPORTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:168 sofia/internal/101@pbx.example.co.id Standard REPORTING, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@pbx.example.co.id) State REPORTING going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/101@pbx.example.co.id) State Change CS_REPORTING -> CS_DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_session.c:1744 Session 3 (sofia/internal/101@pbx.example.co.id) Locked, Waiting on external entities
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_session.c:1762 Session 3 (sofia/internal/101@pbx.example.co.id) Ended
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/101@pbx.example.co.id [CS_DESTROY]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/101@pbx.example.co.id) Running State Change CS_DESTROY (Cur 0 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@pbx.example.co.id) State DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:380 sofia/internal/101@pbx.example.co.id SOFIA DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:175 sofia/internal/101@pbx.example.co.id Standard DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@pbx.example.co.id) State DESTROY going to sleep
the use case:
FusionPBX on 1.1.1.1
ext 101 calls ext105@1.2.3.4 (yealink), by dialing 999105
looks like I missed something:
<extension name="to-yealink" continue="false" uuid="1e4291b">
<condition field="destination_number" expression="^999(\d{3})$">
<action application="set" data="call_direction=external" inline="true"/>
<action application="set" data="hangup_after_bridge=true"/>
<action application="set" data="continue_on_fail=true"/>
<action application="set" data="callee_id_number=1003$1"/>
<action application="bridge" data="sofia/internal/1003$1@1.2.3.4"/>
</condition>
</extension>
== log ==
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/101@pbx.example.co.id [c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_NEW (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [INFO] sofia.c:10459 sofia/internal/101@pbx.example.co.id receiving invite from 149.1.1.1:45090 version: 1.10.11 -release 64bit call-id: f976bb3396cc49b4b5c9e3034efe3668
2024-02-23 16:21:33.711810 98.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 149.1.1.1:0.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.711810 98.97% [DEBUG] switch_core_state_machine.c:600 (sofia/internal/101@pbx.example.co.id) State NEW
2024-02-23 16:21:33.711810 98.97% [DEBUG] sofia.c:2419 detaching session c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
2024-02-23 16:21:33.771811 98.97% [DEBUG] sofia.c:2532 Re-attaching to session c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [INFO] sofia.c:10459 sofia/internal/101@pbx.example.co.id receiving invite from 149.1.1.1:45090 version: 1.10.11 -release 64bit call-id: f976bb3396cc49b4b5c9e3034efe3668
2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:10553 verifying acl "providers" for ip/port 149.1.1.1:0.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:11674 Setting NAT mode based on via received
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/101@pbx.example.co.id entering state [received][100]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7503 Remote SDP:
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb v=0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb o=- 3917694094 3917694094 IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb s=pjmedia
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb b=AS:84
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb t=0 0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=X-nat:0
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb m=audio 4002 RTP/AVP 8 0 101
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb c=IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb b=TIAS:64000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:8 PCMA/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:0 PCMU/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtpmap:101 telephone-event/8000
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=fmtp:101 0-16
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=rtcp:4003 IN IP4 100.96.1.2
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb a=ssrc:1933863199 cname:0c5577877d115880
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7906 (sofia/internal/101@pbx.example.co.id) State Change CS_NEW -> CS_INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_INIT (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/101@pbx.example.co.id) State INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:97 sofia/internal/101@pbx.example.co.id SOFIA INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:40 sofia/internal/101@pbx.example.co.id Standard INIT
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/101@pbx.example.co.id) State Change CS_INIT -> CS_ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/101@pbx.example.co.id) State INIT going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_ROUTING (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:2399 (sofia/internal/101@pbx.example.co.id) Callstate Change DOWN -> RINGING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/101@pbx.example.co.id) State ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:158 sofia/internal/101@pbx.example.co.id SOFIA ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:230 sofia/internal/101@pbx.example.co.id Standard ROUTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [INFO] mod_dialplan_xml.c:639 Processing 101 <101>->999105 in context pbx.example.co.id
2024-02-23 16:21:33.791810 98.97% [NOTICE] switch_cpp.cpp:1466 [xml_handler] multiple key:dialplan
bx.example.co.idc9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->to-pbx.example.co.id] continue=false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id parsing [pbx.example.co.id->to-cc.example.co.id] continue=false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Regex (PASS) [to-cc.example.co.id] destination_number(999105) =~ /^999(\d{3})$/ break=on-false
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(call_direction=external) INLINE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(call_direction=external)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [call_direction]=[external]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(hangup_after_bridge=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(continue_on_fail=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action set(callee_id_number=1003105)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb Dialplan: sofia/internal/101@pbx.example.co.id Action bridge(sofia/internal/1003105@147.1.1.1)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/101@pbx.example.co.id) State Change CS_ROUTING -> CS_EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/101@pbx.example.co.id) State ROUTING going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_EXECUTE (Cur 1 Tot 3)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@pbx.example.co.id) State EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:213 sofia/internal/101@pbx.example.co.id SOFIA EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:323 sofia/internal/101@pbx.example.co.id Standard EXECUTE
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(hangup_after_bridge=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [hangup_after_bridge]=[true]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(continue_on_fail=true)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [continue_on_fail]=[true]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id set(callee_id_number=1003105)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_dptools.c:1671 SET sofia/internal/101@pbx.example.co.id [callee_id_number]=[1003105]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb EXECUTE [depth=0] sofia/internal/101@pbx.example.co.id bridge(sofia/internal/1003105@147.1.1.1)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:1288 sofia/internal/101@pbx.example.co.id EXPORTING[export_vars] [domain_name]=[pbx.example.co.id] to event
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_channel.c:1288 sofia/internal/101@pbx.example.co.id EXPORTING[export_vars] [domain_uuid]=[77527d50-4d75-4756-9b39-f4298ffc455c] to event
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_ivr_originate.c:2301 Parsing global variables
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [NOTICE] switch_channel.c:1142 New Channel sofia/internal/1003105@147.1.1.1 [c6388e90-cae0-4a20-83d7-6de88e48fdc4]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:5110 (sofia/internal/1003105@147.1.1.1) State Change CS_NEW -> CS_INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_INIT (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1003105@147.1.1.1) State INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:97 sofia/internal/1003105@147.1.1.1 SOFIA INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [INFO] sofia_glue.c:1659 sofia/internal/1003105@147.1.1.1 sending invite call-id: (null)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia_glue.c:1662 sofia/internal/1003105@147.1.1.1 sending invite version: 1.10.11 -release 64bit
c6388e90-cae0-4a20-83d7-6de88e48fdc4 Local SDP:
c6388e90-cae0-4a20-83d7-6de88e48fdc4 v=0
c6388e90-cae0-4a20-83d7-6de88e48fdc4 o=FreeSWITCH 1708655549 1708655550 IN IP4 149.129.248.234
c6388e90-cae0-4a20-83d7-6de88e48fdc4 s=FreeSWITCH
c6388e90-cae0-4a20-83d7-6de88e48fdc4 c=IN IP4 149.129.248.234
c6388e90-cae0-4a20-83d7-6de88e48fdc4 t=0 0
c6388e90-cae0-4a20-83d7-6de88e48fdc4 m=audio 24544 RTP/AVP 8 0 9 101 13
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:8 PCMA/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:0 PCMU/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:9 G722/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:101 telephone-event/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=fmtp:101 0-15
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=rtpmap:13 CN/8000
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=ptime:20
c6388e90-cae0-4a20-83d7-6de88e48fdc4 a=sendrecv
c6388e90-cae0-4a20-83d7-6de88e48fdc4
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:40 sofia/internal/1003105@147.1.1.1 Standard INIT
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1003105@147.1.1.1) State Change CS_INIT -> CS_ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:624 (sofia/internal/1003105@147.1.1.1) State INIT going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_ROUTING (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/1003105@147.1.1.1 entering state [calling][0]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1003105@147.1.1.1) State ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] mod_sofia.c:158 sofia/internal/1003105@147.1.1.1 SOFIA ROUTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1003105@147.1.1.1) State Change CS_ROUTING -> CS_CONSUME_MEDIA
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1003105@147.1.1.1) State ROUTING going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1003105@147.1.1.1) State CONSUME_MEDIA
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.791810 98.97% [DEBUG] switch_core_state_machine.c:659 (sofia/internal/1003105@147.1.1.1) State CONSUME_MEDIA going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] sofia.c:6672 Remote Reason: 16
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] sofia.c:7493 Channel sofia/internal/1003105@147.1.1.1 entering state [terminated][480]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] sofia.c:8735 Hangup sofia/internal/1003105@147.1.1.1 [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_HANGUP (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/1003105@147.1.1.1) Callstate Change DOWN -> HANGUP
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1003105@147.1.1.1) State HANGUP
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] mod_sofia.c:469 Channel sofia/internal/1003105@147.1.1.1 hanging up, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:59 sofia/internal/1003105@147.1.1.1 Standard HANGUP, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/1003105@147.1.1.1) State HANGUP going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/1003105@147.1.1.1) State Change CS_HANGUP -> CS_REPORTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/1003105@147.1.1.1) Running State Change CS_REPORTING (Cur 2 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1003105@147.1.1.1) State REPORTING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:168 sofia/internal/1003105@147.1.1.1 Standard REPORTING, cause: NORMAL_CLEARING
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/1003105@147.1.1.1) State REPORTING going to sleep
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/1003105@147.1.1.1) State Change CS_REPORTING -> CS_DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_session.c:1744 Session 4 (sofia/internal/1003105@147.1.1.1) Locked, Waiting on external entities
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_ivr_originate.c:4056 Originate Resulted in Error Cause: 16 [NORMAL_CLEARING]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] switch_core_session.c:1762 Session 4 (sofia/internal/1003105@147.1.1.1) Ended
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/1003105@147.1.1.1 [CS_DESTROY]
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/1003105@147.1.1.1) Running State Change CS_DESTROY (Cur 1 Tot 4)
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1003105@147.1.1.1) State DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] mod_sofia.c:380 sofia/internal/1003105@147.1.1.1 SOFIA DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:175 sofia/internal/1003105@147.1.1.1 Standard DESTROY
c6388e90-cae0-4a20-83d7-6de88e48fdc4 2024-02-23 16:21:33.811810 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/1003105@147.1.1.1) State DESTROY going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [INFO] mod_dptools.c:3635 Originate Failed. Cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_state_machine.c:382 sofia/internal/101@pbx.example.co.id has executed the last dialplan instruction, hanging up.
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_state_machine.c:384 Hangup sofia/internal/101@pbx.example.co.id [CS_EXECUTE] [NORMAL_CLEARING]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@pbx.example.co.id) State EXECUTE going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_HANGUP (Cur 1 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/101@pbx.example.co.id) Callstate Change RINGING -> HANGUP
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@pbx.example.co.id) State HANGUP
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:463 sofia/internal/101@pbx.example.co.id Overriding SIP cause 480 with 480 from the other leg
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:469 Channel sofia/internal/101@pbx.example.co.id hanging up, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:614 Responding to INVITE with: 480
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:59 sofia/internal/101@pbx.example.co.id Standard HANGUP, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@pbx.example.co.id) State HANGUP going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/101@pbx.example.co.id) State Change CS_HANGUP -> CS_REPORTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@pbx.example.co.id) Running State Change CS_REPORTING (Cur 1 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@pbx.example.co.id) State REPORTING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:168 sofia/internal/101@pbx.example.co.id Standard REPORTING, cause: NORMAL_CLEARING
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@pbx.example.co.id) State REPORTING going to sleep
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/101@pbx.example.co.id) State Change CS_REPORTING -> CS_DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_session.c:1744 Session 3 (sofia/internal/101@pbx.example.co.id) Locked, Waiting on external entities
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_session.c:1762 Session 3 (sofia/internal/101@pbx.example.co.id) Ended
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [NOTICE] switch_core_session.c:1766 Close Channel sofia/internal/101@pbx.example.co.id [CS_DESTROY]
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/101@pbx.example.co.id) Running State Change CS_DESTROY (Cur 0 Tot 4)
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@pbx.example.co.id) State DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] mod_sofia.c:380 sofia/internal/101@pbx.example.co.id SOFIA DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:175 sofia/internal/101@pbx.example.co.id Standard DESTROY
c9cf3e7c-8f25-4b63-aeeb-7787b7c1cafb 2024-02-23 16:21:33.831811 98.97% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@pbx.example.co.id) State DESTROY going to sleep
- Status