Getting “488 Not Acceptable Here” on inbound call

iko3 · January 30, 2023, 8:53am

Hello!

I run Asterisk 20.1.0, and have problem with incoming calls.

When I dial in, I get an INVITE, a 488 code and an ACK. Any idea what might be the problem here?

INVITE sip:10000000005@YYY.YYY.114.193:5060 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.146.121:5060;rport;branch=z9hG4bK469540400
From: <sip:10010100000@XXX.XXX.146.121:5060>;tag=1066985979
To: <sip:10000000005@YYY.YYY.114.193:5060>
Call-ID: 121104109
CSeq: 20 INVITE
Contact: <sip:10010100000@XXX.XXX.146.121:5060>
Content-Type: application/sdp
Max-Forwards: 70
User-Agent: eXosip/3.6.0
Subject: This is a call to app.
Content-Length:   159

v=0
o=781146308 0 0 IN IP4 4.0.0.0
s=Talk session
c=IN IP4 4.0.0.0
t=0 0
a=doorFloor:0
a=responseType:0
a=doorType:2
a=isSpecialType:0
a=isConfirm:0


Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[488]: #033[1;37mres_pjsip/pjsip_distributor.c#033[0m:#033[1;37m393#033[0m #033[1;37mfind_dialog#033[0m: Could not find matching transaction for Request msg INVITE/cseq=20 (rdata0x7fafd8004ad8)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[488]: #033[1;37mres_pjsip/pjsip_distributor.c#033[0m:#033[1;37m471#033[0m #033[1;37mast_sip_get_distributor_serializer#033[0m: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=20 (rdata0x7fafd8004ad8)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_endpoint_identifier_ip.c#033[0m:#033[1;37m253#033[0m #033[1;37mip_identify_match_check#033[0m: Source address XXX.XXX.146.121:5060 matches identify 'hikvision'
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_endpoint_identifier_ip.c#033[0m:#033[1;37m287#033[0m #033[1;37mcommon_identify#033[0m: Identify 'hikvision' SIP message matched to endpoint hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4253#033[0m #033[1;37msession_on_rx_request#033[0m:  (null session) Request: INVITE 
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4080#033[0m #033[1;37mhandle_new_invite_request#033[0m:  Request: 
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip/pjsip_distributor.c#033[0m:#033[1;37m471#033[0m #033[1;37mast_sip_get_distributor_serializer#033[0m: Calculated serializer pjsip/distributor-0000002f to use for Request msg INVITE/cseq=20 (rdata0x7fafd8018988)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mchan_pjsip.c#033[0m:#033[1;37m2969#033[0m #033[1;37mchan_pjsip_session_begin#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mchan_pjsip.c#033[0m:#033[1;37m2973#033[0m #033[1;37mchan_pjsip_session_begin#033[0m:  Direct media no glare mitigation
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m3934#033[0m #033[1;37mnew_invite#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4014#033[0m #033[1;37mnew_invite#033[0m:  hikvision: Call (UDP:XXX.XXX.146.121:5060) to extension '10000000005' sending 100 Trying
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4544#033[0m #033[1;37mhandle_outgoing_response#033[0m:  hikvision: Method is INVITE, Response is 100 Trying
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4563#033[0m #033[1;37mhandle_outgoing_response#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4660#033[0m #033[1;37msession_inv_on_state_changed#033[0m:  hikvision Event: TSX_STATE  Inv State: INCOMING
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4686#033[0m #033[1;37msession_inv_on_state_changed#033[0m: hikvision: Source of transaction state change is TX_MSG
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4734#033[0m #033[1;37msession_inv_on_state_changed#033[0m:  
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4778#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  hikvision TSX State: Proceeding  Inv State: INCOMING
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4967#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  Nothing delayed
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4198#033[0m #033[1;37msession_on_tsx_state#033[0m:  hikvision TSX State: Proceeding  Inv State: INCOMING
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4202#033[0m #033[1;37msession_on_tsx_state#033[0m:  Topology: Pending: (null topology)  Active: (null topology)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4207#033[0m #033[1;37msession_on_tsx_state#033[0m:  
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m769#033[0m #033[1;37mhandle_incoming_sdp#033[0m:  hikvision: Media count: 0
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m960#033[0m #033[1;37mhandle_incoming_sdp#033[0m:  hikvision: Handled? no
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4544#033[0m #033[1;37mhandle_outgoing_response#033[0m:  hikvision: Method is INVITE, Response is 488 Not Acceptable Here
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4563#033[0m #033[1;37mhandle_outgoing_response#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4660#033[0m #033[1;37msession_inv_on_state_changed#033[0m:  hikvision Event: TSX_STATE  Inv State: DISCONNCTD
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4686#033[0m #033[1;37msession_inv_on_state_changed#033[0m: hikvision: Source of transaction state change is TX_MSG
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4734#033[0m #033[1;37msession_inv_on_state_changed#033[0m:  
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4778#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  hikvision TSX State: Completed  Inv State: DISCONNCTD
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4800#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  Disconnected
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4198#033[0m #033[1;37msession_on_tsx_state#033[0m:  (null session) TSX State: Completed  Inv State: DISCONNCTD
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4207#033[0m #033[1;37msession_on_tsx_state#033[0m:  
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4063#033[0m #033[1;37mnew_invite#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4154#033[0m #033[1;37mhandle_new_invite_request#033[0m:  Request:  Session: hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4272#033[0m #033[1;37msession_on_rx_request#033[0m:  (null session) Handled request INVITE  ? yes
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mchan_pjsip.c#033[0m:#033[1;37m2990#033[0m #033[1;37mchan_pjsip_session_end#033[0m:  hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mchan_pjsip.c#033[0m:#033[1;37m2993#033[0m #033[1;37mchan_pjsip_session_end#033[0m:  No channel
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m2912#033[0m #033[1;37msession_destructor#033[0m: hikvision: Destroying SIP session
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[488]: #033[1;37mres_pjsip/pjsip_distributor.c#033[0m:#033[1;37m502#033[0m #033[1;37mdistributor#033[0m: Searching for serializer associated with dialog dlg0x7fafe8015a98 for Request msg ACK/cseq=20 (rdata0x7fafd8004ad8)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[488]: #033[1;37mres_pjsip/pjsip_distributor.c#033[0m:#033[1;37m471#033[0m #033[1;37mast_sip_get_distributor_serializer#033[0m: Calculated serializer pjsip/distributor-0000002f to use for Request msg ACK/cseq=20 (rdata0x7fafd8004ad8)
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_endpoint_identifier_ip.c#033[0m:#033[1;37m253#033[0m #033[1;37mip_identify_match_check#033[0m: Source address XXX.XXX.146.121:5060 matches identify 'hikvision'
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_endpoint_identifier_ip.c#033[0m:#033[1;37m287#033[0m #033[1;37mcommon_identify#033[0m: Identify 'hikvision' SIP message matched to endpoint hikvision
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4778#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4790#033[0m #033[1;37msession_inv_on_tsx_state_changed#033[0m:  Session ended
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4198#033[0m #033[1;37msession_on_tsx_state#033[0m:  (null session) TSX State: Confirmed  Inv State: DISCONNCTD
Jan 30 08:26:21 Asterisk: [Jan 30 09:26:21] #033[1;32mDEBUG#033[0m[489]: #033[1;37mres_pjsip_session.c#033[0m:#033[1;37m4207#033[0m #033[1;37msession_on_tsx_state#033[0m:  
Jan 30 08:26:24 Asterisk: [Jan 30 09:26:24] #033[1;32mDEBUG#033[0m[575]: #033[1;37mmanager.c#033[0m:#033[1;37m7099#033[0m #033[1;37mprocess_message#033[0m: Running action 'Status'
Jan 30 08:26:24 Asterisk: [Jan 30 09:26:24] #033[1;32mDEBUG#033[0m[575]: #033[1;37mmanager.c#033[0m:#033[1;37m7099#033[0m #033[1;37mprocess_message#033[0m: Running action 'PJSIPShowEndpoint'
Jan 30 08:26:24 Asterisk: [Jan 30 09:26:24] #033[1;32mDEBUG#033[0m[575]: #033[1;37mmanager.c#033[0m:#033[1;37m7099#033[0m #033[1;37mprocess_message#033[0m: Running action 'PJSIPShowEndpoint'

jcolp · January 30, 2023, 10:10am

There are no streams in the call. We require an audio stream.

system · March 1, 2023, 10:10am

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.