DS-KD8003 - DS-KV8113 - DS-KV8213 - DS-KV6113 - DS-KV8413 and .... integration Hikvision HikConnect Video intercom doorbell

pergola.fabio · January 29, 2023, 11:39am

Also remove the match, maybe not needed…

iko3 · January 29, 2023, 12:19pm

I’m registering at my master indoor station…
I think I need to match by ip address, since I installed Asterisk on my cloud server with external ip address

pergola.fabio · January 29, 2023, 1:14pm

Ah ok, I thought you didn’t have a indoor station, that you don’t need those numbers indeed, it’s just like in readme then…

Maybe it’s because you have asterisk in cloud, try for test the local asterisk? There is an addon

iko3 · January 29, 2023, 4:26pm

I’m getting same error locally when using Asterisk add-on. I’m reading online that 488 error could mean incompatibility of codecs?

pergola.fabio · January 29, 2023, 4:41pm

Hmm, not sure, you can always try to remove the video codec?

Also try changing the script, it’s hardcoded to 5061, so close your asterisk, change script , change 5061 to 5060… Then press the call button, do you see the invite coming in?

iko3 · January 29, 2023, 5:15pm

registration works fine, Hikvision responds back to 5060, like you mentioned in the readme

Tried removing video codecs without luck, still 488 on INVITE …

pergola.fabio · January 29, 2023, 5:51pm

What indoor station do you have? Also search on asterisk forums, lots of threads with 488 issue…

Are you not able to use method 1 without script?

iko3 · January 29, 2023, 5:58pm

I have quite old setup with indoor station DS-KH6310. It doesn’t have the option to register extensions, so I need to use the script.

Your previous opensips add-on worked fine, so it must be something with my Asterisk

pergola.fabio · January 29, 2023, 6:12pm

Indeed, but what ?

Maybe a codec issue?

pergola.fabio · January 29, 2023, 6:18pm

what do you see in the addon asterisk log? not the sip debug

iko3 · January 29, 2023, 7:22pm

checking the logs and there is no even mention of the INVITE message, then I tried to call the door station and that didn’t work ofc

Jan 29 16:48:25 1f6c14d6b777[658]: [Jan 29 17:48:25] #033[1;32mAsterisk Ready.#033[0m
Jan 29 16:48:25 1f6c14d6b777[658]: [Jan 29 17:48:25] #033[1;30m    -- #033[0mAdded contact 'sip:[email protected]:58234' to AOR '8000' with expiration of 60 seconds
Jan 29 16:48:25 1f6c14d6b777[658]: [Jan 29 17:48:25] #033[1;30m  == #033[0mEndpoint 8000 is now Reachable
Jan 29 17:01:54 1f6c14d6b777[658]: [Jan 29 18:01:54] #033[1;30m    -- #033[0mAdded contact 'sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046' to AOR '7000' with expiration of 900 seconds
Jan 29 17:01:54 1f6c14d6b777[658]: [Jan 29 18:01:54] #033[1;30m  == #033[0mEndpoint 7000 is now Reachable
Jan 29 17:01:54 1f6c14d6b777[658]: [Jan 29 18:01:54] #033[1;30m    -- #033[0mRemoved contact 'sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046' from AOR '7000' due to request
Jan 29 17:01:54 1f6c14d6b777[658]: [Jan 29 18:01:54] #033[1;30m  == #033[0mContact 7000/sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046 has been deleted
Jan 29 17:01:54 1f6c14d6b777[658]: [Jan 29 18:01:54] #033[1;30m  == #033[0mEndpoint 7000 is now Unreachable
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m    -- #033[0mAdded contact 'sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046' to AOR '7000' with expiration of 900 seconds
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m  == #033[0mEndpoint 7000 is now Reachable
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m    -- #033[0mRemoved contact 'sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046' from AOR '7000' due to request
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m  == #033[0mContact 7000/sip:[email protected]:50046;rinstance=aef76466b817c4a0;x-ast-orig-host=10.0.1.171:50046 has been deleted
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m  == #033[0mEndpoint 7000 is now Unreachable
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m    -- #033[0mAdded contact 'sip:[email protected]:50046;rinstance=aef76466b817c4a0' to AOR '7000' with expiration of 900 seconds
Jan 29 17:01:55 1f6c14d6b777[658]: [Jan 29 18:01:55] #033[1;30m  == #033[0mEndpoint 7000 is now Reachable
Jan 29 17:02:31 1f6c14d6b777[658]: [Jan 29 18:02:31] #033[1;31mWARNING#033[0m[445][C-00000001]: #033[1;37mpjsip/dialplan_functions.c#033[0m:#033[1;37m1018#033[0m #033[1;37mpjsip_acf_dial_contacts_read#033[0m: Specified endpoint '10010100000' was not found
Jan 29 17:02:31 1f6c14d6b777[658]: [Jan 29 18:02:31] #033[1;30m    -- #033[0mExecuting [10010100000@default:1] #033[1;36mDial#033[0m("#033[1;35mPJSIP/7000-00000000#033[0m", "#033[1;35m#033[0m") in new stack
Jan 29 17:02:31 1f6c14d6b777[658]: [Jan 29 18:02:31] #033[1;30m    -- #033[0mNo devices or endpoints to dial (technology/resource)
Jan 29 17:02:31 1f6c14d6b777[658]: [Jan 29 18:02:31] #033[1;30m    -- #033[0mAuto fallthrough, channel 'PJSIP/7000-00000000' status is 'CHANUNAVAIL'```

pergola.fabio · January 30, 2023, 6:23am

Your asterisk is running on port 5060, right? That’s mandatory, seems invite is always hardcoded to port 5060… Before my opensips was also on 5060, from there forwarded to asterisk on 5050, because it was running on same host

iko3 · January 30, 2023, 8:43am

yes, it is. I use Asterisk add-on with only changes from your readme file. I managed to extract some more logs, where I see the incoming call, but for some reason Asterisk is not accepting it

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'

I see this line might be the reason

handle_incoming_sdp#033[0m:  hikvision: Media count: 0

pergola.fabio · January 30, 2023, 9:08am

Have you tried disabling video by removing it from the trunk and/or sip endpoint? Just remove the video codecs… And only allow ulaw and alaw…

Video doesnt work anyway

iko3 · January 30, 2023, 9:30am

yeah, I tried that too

pergola.fabio · January 30, 2023, 9:48am

and you are running asterisk on same local network/subnet as the indoor device?
or why are you masking the Ip’s ?
maybe its just an firewall port issue? for audio/video traffic you also need RTP ports to be open?

pergola.fabio · January 30, 2023, 9:50am

have you also configured an from_domain ?

pergola.fabio · January 30, 2023, 9:51am

also , are you calling to test an sip softphone ? or are you using the lovelace card as endpoint?
see here:

iko3 · January 30, 2023, 10:43am

I primarily use Asterisk in the cloud, but I’m receiving exact same request/response and error in my local installation as well. So networking part shouldn’t be a problem here.

I configured 3 extensions, one for the Lovelace card, another one for my soft phone, and one for hikvision. I’m able to make a call from soft phone.
I have from_domain=asterisk.com configured for two of the extensions, but not for hikvision.

When I examine the INVITE request, I don’t see any mentions of the media descriptor, is that normal?
Do you possibly have an example of working INVITE message?

pergola.fabio · January 30, 2023, 10:57am

Its indeed strange, I still have the backup of the opensips addon if you want it…

I created a thread before on asterisk about video issue, you can find invite message there and also compare it…

It must be a codec issue