[Freeswitch-users] Cannot Receive Incoming call [CS_NEW] [WRONG_CALL_STATE] Abandonded

Thu Jan 4 12:22:27 UTC 2018

Hello,

i cannot figure out this problem. The incoming call is detailed by FS in Debug, but it doesn’t process throw DialPlan. Mi server IP is 165.227.33.23 and the incoming call is throw 64.34.236.149 as you can see. i get this behaviour:

0595a3c8 2018-01-04 12:18:14.294341 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/34600083765 at 64.34.236.149 [0595a3c8-a696-46b9-addd-0eb3a9f0a2a3]
0595a3c8 2018-01-04 12:18:14.294341 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/34600083765 at 64.34.236.149) Running State Change CS_NEW (Cur 4 Tot 2448)
0595a3c8 2018-01-04 12:18:14.294341 [DEBUG] sofia.c:9873 sofia/internal/34600083765 at 64.34.236.149 receiving invite from 64.34.236.149:5060 version: 1.6.19 -36-7a77e0b 64bit
2018-01-04 12:18:14.294341 [DEBUG] sofia.c:10044 IP 64.34.236.149 Rejected by acl "domains". Falling back to Digest auth.
nua_stack.c:573 nua_stack_signal() nua(0x7f3c180f5900): recv signal r_respond 407 Proxy Authentication Required
0595a3c8 2018-01-04 12:18:14.294341 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/34600083765 at 64.34.236.149) State NEW
send 858 bytes to udp/[64.34.236.149]:5060 at 12:18:14.306835:
   ------------------------------------------------------------------------
   SIP/2.0 407 Proxy Authentication Required
   Via: SIP/2.0/UDP 64.34.236.149:5060;branch=z9hG4bK6dcba8c3
   From: "34600083765" <sip:34600083765 at 64.34.236.149>;tag=as134ae412
   To: <sip:5508813053301091 at 165.227.33.23:5060>;tag=2HZXytpDN07tN
   Call-ID: 7d898457242fdb9e02387bd91238036b at 64.34.236.149:5060
   CSeq: 102 INVITE
   User-Agent: FreeSWITCH-mod_sofia/1.6.19-36-7a77e0b~64bit
   Accept: application/sdp
   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
   Supported: timer, path, replaces
   Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
   Proxy-Authenticate: Digest realm="64.34.236.149", nonce="9e208456-94e6-4cff-9ed9-e4abcf1075aa", algorithm=MD5, qop="auth"
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:6791 incoming_reply() nta: sent 407 Proxy Authentication Required for INVITE (102)
nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7f3c180f5900): removing session usage
nua_session.c:4139 signal_call_state_change() nua(0x7f3c180f5900): call state changed: received -> terminated
nua_stack.c:271 nua_stack_event() nua(0x7f3c180f5900): event i_state 407 Proxy Authentication Required
nua_stack.c:271 nua_stack_event() nua(0x7f3c180f5900): event i_terminated 407 Proxy Authentication Required
2018-01-04 12:18:14.294341 [DEBUG] sofia.c:2334 detaching session 0595a3c8-a696-46b9-addd-0eb3a9f0a2a3
nua_stack.c:569 nua_stack_signal() nua(0x7f3c180f5900): recv signal r_destroy
recv 437 bytes from udp/[64.34.236.149]:5060 at 12:18:14.318108:
   ------------------------------------------------------------------------
   ACK sip:5508813053301091 at 165.227.33.23:5060 SIP/2.0
   Via: SIP/2.0/UDP 64.34.236.149:5060;branch=z9hG4bK6dcba8c3
   Max-Forwards: 70
   From: "34600083765" <sip:34600083765 at 64.34.236.149>;tag=as134ae412
   To: <sip:5508813053301091 at 165.227.33.23:5060>;tag=2HZXytpDN07tN
   Contact: <sip:34600083765 at 64.34.236.149:5060>
   Call-ID: 7d898457242fdb9e02387bd91238036b at 64.34.236.149:5060
   CSeq: 102 ACK
   User-Agent: Tech Call Flow
   Content-Length: 0

   ------------------------------------------------------------------------
nta.c:2880 agent_recv_request() nta: received ACK sip:5508813053301091 at 165.227.33.23:5060 SIP/2.0 (CSeq 102)
nta.c:3019 agent_recv_request() nta: ACK (102) is going to INVITE (102)
nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 407 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/7 term, 1/8 free
nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 407 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/6 term, 1/7 free
nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 407 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/5 term, 1/6 free
nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 407 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/4 term, 1/5 free
nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 401 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/4 free
nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 403 response
nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/3 free
6dcaead6 2018-01-04 12:18:20.814342 [WARNING] switch_core_state_machine.c:687 6dcaead6-8ea6-416a-8656-bca9ce8038a7 sofia/internal/34600083765 at 64.34.236.149 Abandoned
6dcaead6 2018-01-04 12:18:20.814342 [NOTICE] switch_core_state_machine.c:690 Hangup sofia/internal/34600083765 at 64.34.236.149 [CS_NEW] [WRONG_CALL_STATE]
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/34600083765 at 64.34.236.149) Running State Change CS_HANGUP (Cur 4 Tot 2448)
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/34600083765 at 64.34.236.149) Callstate Change DOWN -> HANGUP
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/34600083765 at 64.34.236.149) State HANGUP
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] mod_sofia.c:438 Channel sofia/internal/34600083765 at 64.34.236.149 hanging up, cause: WRONG_CALL_STATE
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:60 sofia/internal/34600083765 at 64.34.236.149 Standard HANGUP, cause: WRONG_CALL_STATE
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/34600083765 at 64.34.236.149) State HANGUP going to sleep
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/34600083765 at 64.34.236.149) State Change CS_HANGUP -> CS_REPORTING
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/34600083765 at 64.34.236.149) Running State Change CS_REPORTING (Cur 4 Tot 2448)
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/34600083765 at 64.34.236.149) State REPORTING
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:174 sofia/internal/34600083765 at 64.34.236.149 Standard REPORTING, cause: WRONG_CALL_STATE
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/34600083765 at 64.34.236.149) State REPORTING going to sleep
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/34600083765 at 64.34.236.149) State Change CS_REPORTING -> CS_DESTROY
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_session.c:1665 Session 2445 (sofia/internal/34600083765 at 64.34.236.149) Locked, Waiting on external entities
6dcaead6 2018-01-04 12:18:20.814342 [NOTICE] switch_core_session.c:1683 Session 2445 (sofia/internal/34600083765 at 64.34.236.149) Ended
6dcaead6 2018-01-04 12:18:20.814342 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/34600083765 at 64.34.236.149 [CS_DESTROY]
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/34600083765 at 64.34.236.149) Running State Change CS_DESTROY (Cur 3 Tot 2448)
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/34600083765 at 64.34.236.149) State DESTROY
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] mod_sofia.c:343 sofia/internal/34600083765 at 64.34.236.149 SOFIA DESTROY
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:181 sofia/internal/34600083765 at 64.34.236.149 Standard DESTROY
6dcaead6 2018-01-04 12:18:20.814342 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/34600083765 at 64.34.236.149) State DESTROY going to sleep