SOLVED Responding to INVITE with: 480

Status
Not open for further replies.
S

sheraz14

Member
Apr 24, 2018
33
0
6
41
Hi
its been 2 days but im struggling to get my incoming call work
i've provider who is sending the call but my Fusionpbx is responding with 480

Logs are Below and appreciate if you can help here


24b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/016469804834@31.168.230.135 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_codec.c:111 sofia/internal/016469804834@31.168.230.135 Original read codec set to PCMA:8
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_media.c:5911 sofia/internal/016469804834@31.168.230.135 Set 2833 dtmf send payload to 101 recv payload to 101
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] sofia.c:7760 (sofia/internal/016469804834@31.168.230.135) State Change CS_NEW -> CS_INIT
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/016469804834@31.168.230.135) State NEW
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_INIT (Cur 1 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.135) State INIT
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] mod_sofia.c:93 sofia/internal/016469804834@31.168.230.135 SOFIA INIT
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:40 sofia/internal/016469804834@31.168.230.135 Standard INIT
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/016469804834@31.168.230.135) State Change CS_INIT -> CS_ROUTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.230.135) State INIT going to sleep
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_ROUTING (Cur 1 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_channel.c:2332 (sofia/internal/016469804834@31.168.230.135) Callstate Change DOWN -> RINGING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.135) State ROUTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] mod_sofia.c:154 sofia/internal/016469804834@31.168.230.135 SOFIA ROUTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [DEBUG] switch_core_state_machine.c:236 sofia/internal/016469804834@31.168.230.135 Standard ROUTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.877124 [INFO] mod_dialplan_xml.c:637 Processing 0733134837 <016469804834>->102 in context public
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 parsing [public->caller-details] continue=true
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Regex (PASS) [caller-details] () =~ // break=never
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Action export(call_direction=inbound) INLINE
c424b87c-fa83-4db4-a9a4-d08034d3e786 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.135 export(call_direction=inbound)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Action set(caller_destination=${sip_to_user}) INLINE
c424b87c-fa83-4db4-a9a4-d08034d3e786 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.135 set(caller_destination=102)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.135 [caller_destination]=[102]
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Action set(caller_id_name=${caller_id_name}) INLINE
c424b87c-fa83-4db4-a9a4-d08034d3e786 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.135 set(caller_id_name=0733134837)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.135 [caller_id_name]=[0733134837]
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Action set(caller_id_number=${caller_id_number})
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 parsing [public->9176312716] continue=false
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Regex (FAIL) [9176312716] destination_number(102) =~ /^(9176312716)$/ break=on-false
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 parsing [public->0733134837] continue=false
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Regex (FAIL) [0733134837] destination_number(102) =~ /^(0733134837)$/ break=on-false
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 parsing [public->0548459164] continue=false
c424b87c-fa83-4db4-a9a4-d08034d3e786 Dialplan: sofia/internal/016469804834@31.168.230.135 Regex (FAIL) [0548459164] destination_number(102) =~ /^(0548459164)$/ break=on-false
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/016469804834@31.168.230.135) State Change CS_ROUTING -> CS_EXECUTE
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.230.135) State ROUTING going to sleep
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_EXECUTE (Cur 1 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.135) State EXECUTE
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_sofia.c:209 sofia/internal/016469804834@31.168.230.135 SOFIA EXECUTE
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:329 sofia/internal/016469804834@31.168.230.135 Standard EXECUTE
c424b87c-fa83-4db4-a9a4-d08034d3e786 EXECUTE [depth=0] sofia/internal/016469804834@31.168.230.135 set(caller_id_number=016469804834)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.230.135 [caller_id_number]=[016469804834]
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [NOTICE] switch_core_state_machine.c:386 sofia/internal/016469804834@31.168.230.135 has executed the last dialplan instruction, hanging up.
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/016469804834@31.168.230.135 [CS_EXECUTE] [NORMAL_CLEARING]
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.230.135) State EXECUTE going to sleep
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_HANGUP (Cur 1 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/016469804834@31.168.230.135) Callstate Change RINGING -> HANGUP
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.135) State HANGUP
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_sofia.c:453 Channel sofia/internal/016469804834@31.168.230.135 hanging up, cause: NORMAL_CLEARING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:60 sofia/internal/016469804834@31.168.230.135 Standard HANGUP, cause: NORMAL_CLEARING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.230.135) State HANGUP going to sleep
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/016469804834@31.168.230.135) State Change CS_HANGUP -> CS_REPORTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_REPORTING (Cur 1 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.897070 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.135) State REPORTING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:174 sofia/internal/016469804834@31.168.230.135 Standard REPORTING, cause: NORMAL_CLEARING
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.230.135) State REPORTING going to sleep
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/016469804834@31.168.230.135) State Change CS_REPORTING -> CS_DESTROY
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_session.c:1726 Session 100418 (sofia/internal/016469804834@31.168.230.135) Locked, Waiting on external entities
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [NOTICE] switch_core_session.c:1744 Session 100418 (sofia/internal/016469804834@31.168.230.135) Ended
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/016469804834@31.168.230.135 [CS_DESTROY]
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_DESTROY (Cur 0 Tot 100418)
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.135) State DESTROY
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] mod_sofia.c:364 sofia/internal/016469804834@31.168.230.135 SOFIA DESTROY
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:181 sofia/internal/016469804834@31.168.230.135 Standard DESTROY
c424b87c-fa83-4db4-a9a4-d08034d3e786 2020-09-29 10:15:26.937084 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.230.135) State DESTROY going to sleep
5e032c85-fea0-4511-9bfc-c3db471c303a 2020-09-29 10:15:32.077100 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/016469804834@31.168.230.135 [5e032c85-fea0-4511-9bfc-c3db471c303a]
5e032c85-fea0-4511-9bfc-c3db471c303a 2020-09-29 10:15:32.077100 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.230.135) Running State Change CS_NEW (Cur 1 Tot 100419)
5e032c85-fea0-4511-9bfc-c3db471c303a 2020-09-29 10:15:32.077100 [DEBUG] sofia.c:10280 sofia/internal/016469804834@31.168.230.135 receiving invite from 31.168.230.135:5060 version: 1.10.5 -release.8 64bit
2020-09-29 10:15:32.077100 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 31.168.230.135:0.
2020-09-29 10:15:32.077100 [DEBUG] sofia.c:10403 IP 31.168.230.135 Approved by acl "domains[]". Access Granted.
5e032c85-fea0-4511-9bfc-c3db471c303a 2020-09-29 10:15:32.077100 [DEBUG] sofia.c:7326 Channel sofia/internal/016469804834@31.168.230.135 entering state [received][100]
5e032c85-fea0-4511-9bfc-c3db471c303a 2020-09-29 10:15:32.077100 [DEBUG] sofia.c:7336 Remote SDP:
5e032c85-fea0-4511-9bfc-c3db471c303a v=0
5e032c85-fea0-4511-9bfc-c3db471c303a o=root 1237709035 1237709035 IN IP4 31.168.230.135
5e032c85-fea0-4511-9bfc-c3db471c303a s=Asterisk PBX 11.18.0
5e032c85-fea0-4511-9bfc-c3db471c303a c=IN IP4 31.168.230.135
5e032c85-fea0-4511-9bfc-c3db471c303a t=0 0
5e032c85-fea0-4511-9bfc-c3db471c303a m=audio 11282 RTP/AVP 8 101
5e032c85-fea0-4511-9bfc-c3db471c303a a=rtpmap:8 PCMA/8000
5e032c85-fea0-4511-9bfc-c3db471c303a a=rtpmap:101 telephone-event/8000
5e032c85-fea0-4511-9bfc-c3db471c303a a=fmtp:101 0-16
5e032c85-fea0-4511-9bfc-c3db471c303a a=ptime:20
5e032c85-fea0-4511-9bfc-c3db471c303a



1601378713355.png
1601378730848.png


CDR

1601380104516.png
 
Last edited:
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
What are those ACL entries? The only ACL entries you should have in there are carriers.
 
S

sheraz14

Member
Apr 24, 2018
33
0
6
41
Hi DigitalDaz,

Thank you for responding

the one i circled is the carrier IP's . I'm doing IP based Trunking.

If im doing it wrong, can you please tell me where exectly i should go and add the IP ? also i can add the range ?

1601380672532.png
 
S

sheraz14

Member
Apr 24, 2018
33
0
6
41
Also what i noticed is the Sip invite is coming with port 5060

be377ee5-42ec-4e2e-9154-a4c71b24454d 2020-09-29 12:04:25.419893 [DEBUG] sofia.c:10280 sofia/internal/016469804834@31.168.3.201 receiving invite from 31.168.3.201:5060 version: 1.10.5 -release.8 64bit

I changed the port from 5080 to 5060 in the below section
Advanced > Variables, under SIP Profile: External, you will need to set external_sip_port to 5060.

But still its the same
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
OK, if they are carriers that is OK, what is the one in the LAN list?
 
S

sheraz14

Member
Apr 24, 2018
33
0
6
41
Hi
the one i circled is the FusionPbx Own IP
the other one 192.168.42.42 is the default entry, which i did not add and i did not thought of removing it

1601382294376.png
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
That will probably be it, remove it, flush you caches, reload acl and maybe even restart freeswitch.
 
S

sheraz14

Member
Apr 24, 2018
33
0
6
41
Hi
here you go


f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/6353@164.68.104.165) State HANGUP
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] mod_sofia.c:453 Channel sofia/internal/6353@164.68.104.165 hanging up, cause: WRONG_CALL_STATE
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:60 sofia/internal/6353@164.68.104.165 Standard HANGUP, cause: WRONG_CALL_STATE
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/6353@164.68.104.165) State HANGUP going to sleep
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/6353@164.68.104.165) State Change CS_HANGUP -> CS_REPORTING
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/6353@164.68.104.165) Running State Change CS_REPORTING (Cur 1 Tot 141)
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/6353@164.68.104.165) State REPORTING
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:174 sofia/internal/6353@164.68.104.165 Standard REPORTING, cause: WRONG_CALL_STATE
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/6353@164.68.104.165) State REPORTING going to sleep
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/6353@164.68.104.165) State Change CS_REPORTING -> CS_DESTROY
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_session.c:1726 Session 140 (sofia/internal/6353@164.68.104.165) Locked, Waiting on external entities
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [NOTICE] switch_core_session.c:1744 Session 140 (sofia/internal/6353@164.68.104.165) Ended
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/6353@164.68.104.165 [CS_DESTROY]
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/6353@164.68.104.165) Running State Change CS_DESTROY (Cur 0 Tot 141)
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/6353@164.68.104.165) State DESTROY
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] mod_sofia.c:364 sofia/internal/6353@164.68.104.165 SOFIA DESTROY
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:181 sofia/internal/6353@164.68.104.165 Standard DESTROY
9f5d9094-179f-4571-b816-5e43298249e0 2020-09-29 13:19:01.718244 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/6353@164.68.104.165) State DESTROY going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.858238 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/016469804834@31.168.3.195 [5efca00f-5f7b-425f-9858-b91beb41d68a]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.858238 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_NEW (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.858238 [DEBUG] sofia.c:10280 sofia/internal/016469804834@31.168.3.195 receiving invite from 31.168.3.195:5060 version: 1.10.5 -release.8 64bit
2020-09-29 13:19:01.858238 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 31.168.3.195:0.
2020-09-29 13:19:01.858238 [DEBUG] sofia.c:10403 IP 31.168.3.195 Approved by acl "domains[]". Access Granted.
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] sofia.c:7326 Channel sofia/internal/016469804834@31.168.3.195 entering state [received][100]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] sofia.c:7336 Remote SDP:
5efca00f-5f7b-425f-9858-b91beb41d68a v=0
5efca00f-5f7b-425f-9858-b91beb41d68a o=root 215770852 215770852 IN IP4 31.168.3.195
5efca00f-5f7b-425f-9858-b91beb41d68a s=Asterisk PBX 11.18.0
5efca00f-5f7b-425f-9858-b91beb41d68a c=IN IP4 31.168.3.195
5efca00f-5f7b-425f-9858-b91beb41d68a t=0 0
5efca00f-5f7b-425f-9858-b91beb41d68a m=audio 11064 RTP/AVP 8 101
5efca00f-5f7b-425f-9858-b91beb41d68a a=rtpmap:8 PCMA/8000
5efca00f-5f7b-425f-9858-b91beb41d68a a=rtpmap:101 telephone-event/8000
5efca00f-5f7b-425f-9858-b91beb41d68a a=fmtp:101 0-16
5efca00f-5f7b-425f-9858-b91beb41d68a a=ptime:20
5efca00f-5f7b-425f-9858-b91beb41d68a
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5594 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5649 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5510 Set telephone-event payload to 101@8000
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:3839 Set Codec sofia/internal/016469804834@31.168.3.195 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_codec.c:111 sofia/internal/016469804834@31.168.3.195 Original read codec set to PCMA:8
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5853 Set telephone-event payload to 101@8000
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_media.c:5911 sofia/internal/016469804834@31.168.3.195 Set 2833 dtmf send payload to 101 recv payload to 101
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] sofia.c:7760 (sofia/internal/016469804834@31.168.3.195) State Change CS_NEW -> CS_INIT
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/016469804834@31.168.3.195) State NEW
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_INIT (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.3.195) State INIT
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_sofia.c:93 sofia/internal/016469804834@31.168.3.195 SOFIA INIT
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:40 sofia/internal/016469804834@31.168.3.195 Standard INIT
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/016469804834@31.168.3.195) State Change CS_INIT -> CS_ROUTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/016469804834@31.168.3.195) State INIT going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_ROUTING (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_channel.c:2332 (sofia/internal/016469804834@31.168.3.195) Callstate Change DOWN -> RINGING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.3.195) State ROUTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_sofia.c:154 sofia/internal/016469804834@31.168.3.195 SOFIA ROUTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:236 sofia/internal/016469804834@31.168.3.195 Standard ROUTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [INFO] mod_dialplan_xml.c:637 Processing 0733134837 <016469804834>->102 in context public
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 parsing [public->caller-details] continue=true
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Regex (PASS) [caller-details] () =~ // break=never
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action export(call_direction=inbound) INLINE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 export(call_direction=inbound)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action set(caller_destination=${sip_to_user}) INLINE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 set(caller_destination=102)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.3.195 [caller_destination]=[102]
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action set(caller_id_name=${caller_id_name}) INLINE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 set(caller_id_name=0733134837)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.3.195 [caller_id_name]=[0733134837]
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action set(caller_id_number=${caller_id_number})
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 parsing [public->0733134837] continue=false
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Regex (FAIL) [0733134837] destination_number(102) =~ /^(0733134837)$/ break=on-false
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 parsing [public->not-found] continue=false
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Regex (PASS) [not-found] () =~ // break=on-false
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action export(call_direction=inbound) INLINE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 export(call_direction=inbound)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [call_direction]=[inbound]
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action set(call_direction=inbound) INLINE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 set(call_direction=inbound)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.3.195 [call_direction]=[inbound]
5efca00f-5f7b-425f-9858-b91beb41d68a Dialplan: sofia/internal/016469804834@31.168.3.195 Action log(WARNING [inbound routes] 404 not found ${sip_network_ip})
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/016469804834@31.168.3.195) State Change CS_ROUTING -> CS_EXECUTE
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/016469804834@31.168.3.195) State ROUTING going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_EXECUTE (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.3.195) State EXECUTE
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_sofia.c:209 sofia/internal/016469804834@31.168.3.195 SOFIA EXECUTE
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:329 sofia/internal/016469804834@31.168.3.195 Standard EXECUTE
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 set(caller_id_number=016469804834)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_dptools.c:1672 SET sofia/internal/016469804834@31.168.3.195 [caller_id_number]=[016469804834]
5efca00f-5f7b-425f-9858-b91beb41d68a EXECUTE [depth=0] sofia/internal/016469804834@31.168.3.195 log(WARNING [inbound routes] 404 not found 31.168.3.195)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [WARNING] mod_dptools.c:1866 [inbound routes] 404 not found 31.168.3.195
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [NOTICE] switch_core_state_machine.c:386 sofia/internal/016469804834@31.168.3.195 has executed the last dialplan instruction, hanging up.
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/016469804834@31.168.3.195 [CS_EXECUTE] [NORMAL_CLEARING]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/016469804834@31.168.3.195) State EXECUTE going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_HANGUP (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/016469804834@31.168.3.195) Callstate Change RINGING -> HANGUP
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.3.195) State HANGUP
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_sofia.c:453 Channel sofia/internal/016469804834@31.168.3.195 hanging up, cause: NORMAL_CLEARING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:60 sofia/internal/016469804834@31.168.3.195 Standard HANGUP, cause: NORMAL_CLEARING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/016469804834@31.168.3.195) State HANGUP going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/016469804834@31.168.3.195) State Change CS_HANGUP -> CS_REPORTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_REPORTING (Cur 1 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.878226 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.3.195) State REPORTING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:174 sofia/internal/016469804834@31.168.3.195 Standard REPORTING, cause: NORMAL_CLEARING
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/016469804834@31.168.3.195) State REPORTING going to sleep
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/016469804834@31.168.3.195) State Change CS_REPORTING -> CS_DESTROY
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_session.c:1726 Session 142 (sofia/internal/016469804834@31.168.3.195) Locked, Waiting on external entities
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [NOTICE] switch_core_session.c:1744 Session 142 (sofia/internal/016469804834@31.168.3.195) Ended
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/016469804834@31.168.3.195 [CS_DESTROY]
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_DESTROY (Cur 0 Tot 142)
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.3.195) State DESTROY
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] mod_sofia.c:364 sofia/internal/016469804834@31.168.3.195 SOFIA DESTROY
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:181 sofia/internal/016469804834@31.168.3.195 Standard DESTROY
5efca00f-5f7b-425f-9858-b91beb41d68a 2020-09-29 13:19:01.918251 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/016469804834@31.168.3.195) State DESTROY going to sleep
96df33a0-ed6d-425e-bc7b-f083609b9edf 2020-09-29 13:19:07.078248 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/016469804834@31.168.3.195 [96df33a0-ed6d-425e-bc7b-f083609b9edf]
96df33a0-ed6d-425e-bc7b-f083609b9edf 2020-09-29 13:19:07.078248 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/016469804834@31.168.3.195) Running State Change CS_NEW (Cur 1 Tot 143)
96df33a0-ed6d-425e-bc7b-f083609b9edf 2020-09-29 13:19:07.078248 [DEBUG] sofia.c:10280 sofia/internal/016469804834@31.168.3.195 receiving invite from 31.168.3.195:5060 version: 1.10.5 -release.8 64bit
2020-09-29 13:19:07.078248 [DEBUG] sofia.c:10374 verifying acl "domains" for ip/port 31.168.3.195:0.
2020-09-29 13:19:07.078248 [DEBUG] sofia.c:10403 IP 31.168.3.195 Approved by acl "domains[]". Access Granted.
96df33a0-ed6d-425e-bc7b-f083609b9edf 2020-09-29 13:19:07.078248 [DEBUG] sofia.c:7326 Channel sofia/internal/016469804834@31.168.3.195 entering state [received][100]
96df33a0-ed6d-425e-bc7b-f083609b9edf 2020-09-29 13:19:07.078248 [DEBUG] sofia.c:7336 Remote SDP:
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,045
566
113
I cannot understand this:

mod_dialplan_xml.c:637 Processing 0733134837 <016469804834>->102 in context public

Where is this 102 coming from.
 
Status
Not open for further replies.
Top Bottom