IpCamera: New IP Camera Binding

higgers · August 16, 2019, 8:18am

If I remember correctly I had to enable the ONVIF setting in my cameras and there was one occasion when upgrading the firmware turned off the setting. I think I also had to create an operator user on the camera and use this operator username and password on the binding.

dastrix80 · August 16, 2019, 8:44am

Thanks Steve, I would imagine that to be true, but I can pull the substream just fine without using any user/pass. In any case, ill create a new user and see if that assists

dastrix80 · August 16, 2019, 9:29am

lol! These errors are hilarious. This is after creating a new user

19:28:46.874 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel       URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
io.netty.util.concurrent.BlockingOperationException: DefaultChannelPromise@495e3e0a(incomplete)
        at io.netty.util.concurrent.DefaultPromise.checkDeadLock(DefaultPromise.java:386) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.checkDeadLock(DefaultChannelPromise.java:159) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:236) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:137) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:30) ~[237:io.netty.transport:4.1.34.Final]
        at org.openhab.binding.ipcamera.handler.IpCameraHandler.sendHttpRequest(IpCameraHandler.java:543) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.processAuth(MyNettyAuthHandler.java:126) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.channelRead(MyNettyAuthHandler.java:223) ~[?:?]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:438) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:323) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:310) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:426) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:278) [233:io.netty.codec:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:253) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286) [232:io.netty.handler:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1408) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:930) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:677) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:612) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:529) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:491) [237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:905) [235:io.netty.common:4.1.34.Final]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [235:io.netty.common:4.1.34.Final]
        at java.lang.Thread.run(Thread.java:748) [?:?]
19:28:51.592 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - The alarm stream was not running for camera 192.168.1.200, re-starting it now
19:28:51.592 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - The alarm stream was not running for camera 192.168.1.201, re-starting it now
19:28:51.595 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - The alarm stream was not running for camera 192.168.1.203, re-starting it now
19:28:51.594 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - The alarm stream was not running for camera 192.168.1.202, re-starting it now
19:28:53.738 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel       URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
io.netty.util.concurrent.BlockingOperationException: DefaultChannelPromise@294c34ac(incomplete)
        at io.netty.util.concurrent.DefaultPromise.checkDeadLock(DefaultPromise.java:386) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.checkDeadLock(DefaultChannelPromise.java:159) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:236) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:137) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:30) ~[237:io.netty.transport:4.1.34.Final]
        at org.openhab.binding.ipcamera.handler.IpCameraHandler.sendHttpRequest(IpCameraHandler.java:543) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.processAuth(MyNettyAuthHandler.java:126) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.channelRead(MyNettyAuthHandler.java:223) ~[?:?]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:438) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:323) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:310) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:426) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:278) [233:io.netty.codec:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:253) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286) [232:io.netty.handler:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1408) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:930) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:677) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:612) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:529) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:491) [237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:905) [235:io.netty.common:4.1.34.Final]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [235:io.netty.common:4.1.34.Final]
        at java.lang.Thread.run(Thread.java:748) [?:?]
19:28:54.096 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel       URL:/ISAPI/Event/notification/alertStream Cause reported is: {}
io.netty.util.concurrent.BlockingOperationException: DefaultChannelPromise@74014e51(incomplete)
        at io.netty.util.concurrent.DefaultPromise.checkDeadLock(DefaultPromise.java:386) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.checkDeadLock(DefaultChannelPromise.java:159) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:236) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:137) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:30) ~[237:io.netty.transport:4.1.34.Final]
        at org.openhab.binding.ipcamera.handler.IpCameraHandler.sendHttpRequest(IpCameraHandler.java:543) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.processAuth(MyNettyAuthHandler.java:126) ~[?:?]
        at org.openhab.binding.ipcamera.internal.MyNettyAuthHandler.channelRead(MyNettyAuthHandler.java:223) ~[?:?]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler$DelegatingChannelHandlerContext.fireChannelRead(CombinedChannelDuplexHandler.java:438) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:323) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.fireChannelRead(ByteToMessageDecoder.java:310) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.callDecode(ByteToMessageDecoder.java:426) [233:io.netty.codec:4.1.34.Final]
        at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:278) [233:io.netty.codec:4.1.34.Final]
        at io.netty.channel.CombinedChannelDuplexHandler.channelRead(CombinedChannelDuplexHandler.java:253) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.handler.timeout.IdleStateHandler.channelRead(IdleStateHandler.java:286) [232:io.netty.handler:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:337) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1408) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:359) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:345) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:930) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:163) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:677) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:612) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:529) [237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:491) [237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:905) [235:io.netty.common:4.1.34.Final]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) [235:io.netty.common:4.1.34.Final]
        at java.lang.Thread.run(Thread.java:748) [?:?]
19:28:55.582 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
19:29:02.588 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
19:29:02.589 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null

Kim_Andersen · August 16, 2019, 10:45am

Did you clear cache and tmp as well?

dastrix80 · August 16, 2019, 10:56am

Yes I did.

higgers · August 16, 2019, 4:49pm

That’s strange, I have 3 Hikvision cameras running without any problems, what are the model numbers of your cameras?

dastrix80 · August 16, 2019, 8:04pm

They are CD2355s and CD2342s

Can you provide your configuration Steve?

matt1 · August 17, 2019, 10:13am

@dastrix80
I am 100% sure you are running an old JAR and not the latest, multiple log entries you have posted prove they do not match what the current JAR will insert into the logs.

Re download the latest version and place all files from the zip into the addons folder.
Make sure you only have the 1.4 version of xml SOAP in the addons folder and not two different versions.
Clean cache and temp and REBOOT by using this line in linux…

sudo service openhab2 stop && sudo rm -rf /var/lib/openhab2/cache/* && sudo rm -rf /var/lib/openhab2/tmp/* && sudo reboot

You must use admin rights to talk to your cameras and you must create an ONVIF user and password and also enable the cgi/API features in the cameras control panel.
Try and get 1 single camera working first before trying multiples as often you need to increase the heap size.

dastrix80 · August 18, 2019, 12:28am

Hi Matt

Im using this version now. I am using the admin user / pass

bundle:list | grep Camera
238 x Active x 80 x 2.5.0.201908030247 x openHAB Add-ons :: Bundles :: IpCamera Binding
openhab>

Here shows the soap version

 ls /usr/share/openhab2/addons/
doorbird-deps-1.0.0.jar        netty-codec-http-4.1.34.Final.jar  netty-transport-4.1.34.Final.jar                          org.openhab.binding.broadlink-2.2.0-BETA2.jar    README
javax.xml.soap-api-1.4.0.jar   netty-common-4.1.34.Final.jar      openhab-addons-2.4.0.kar                                  org.openhab.binding.doorbird-2.5.0-SNAPSHOT.jar
netty-buffer-4.1.34.Final.jar  netty-handler-4.1.34.Final.jar     org.apache.servicemix.bundles.xstream-1.4.7_1.jar         org.openhab.binding.ipcamera-2.5.0-SNAPSHOT.jar
netty-codec-4.1.34.Final.jar   netty-resolver-4.1.34.Final.jar    org.openhab.binding.amazonechocontrol-2.5.0-SNAPSHOT.jar  org.openhab.binding.zwave-2.5.0-SNAPSHOT.jar

and heres another error after a restart


10:29:36.946 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
10:29:37.483 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
10:29:37.484 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
10:29:38.956 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
10:29:39.957 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler              URL:null
10:29:42.749 [INFO ] [smarthome.event.ItemStateChangedEvent] - Entrance_Motion_Alarm changed from ON to OFF
10:29:46.275 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera Dispose() called.
10:29:46.275 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Driveway' changed from ONLINE to UNINITIALIZED
10:29:46.276 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera restarting.
10:29:46.279 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Driveway' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
10:29:46.280 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:North_Yard' changed from ONLINE to UNINITIALIZED
10:29:46.280 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera Dispose() called.
10:29:46.281 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera restarting.
10:29:46.284 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera Dispose() called.
10:29:46.284 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:North_Yard' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
10:29:46.284 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera restarting.
10:29:46.285 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:South_Steps' changed from ONLINE to UNINITIALIZED
10:29:46.288 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:South_Steps' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
10:29:46.288 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera Dispose() called.
10:29:46.289 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Carport' changed from ONLINE to UNINITIALIZED
10:29:46.289 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - ipCamera restarting.
10:29:46.292 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Carport' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
10:29:46.333 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'ipcamera:HIKVISION:North_Yard' has been updated.
10:29:46.334 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'ipcamera:HIKVISION:South_Steps' has been updated.
10:29:46.334 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'ipcamera:HIKVISION:Driveway' has been updated.
10:29:46.335 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'ipcamera:HIKVISION:Carport' has been updated.
10:29:46.337 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:North_Yard' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
10:29:46.340 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:South_Steps' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
10:29:46.342 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Driveway' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
10:29:46.344 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Carport' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
10:29:47.350 [ERROR] [ml.internal.messaging.saaj.client.p2p] - SAAJ0008: Bad Response; Not Found
10:29:47.350 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - SOAP error when trying to connect with ONVIF. This may indicate your camera does not fully support ONVIF, check for an updated firmware for your camera. Will try and connect with HTTP. Camera at IP:192.168.1.203, fault was com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: Bad response: (404Not Found
10:29:47.350 [ERROR] [ml.internal.messaging.saaj.client.p2p] - SAAJ0008: Bad Response; Not Found
10:29:47.350 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - SOAP error when trying to connect with ONVIF. This may indicate your camera does not fully support ONVIF, check for an updated firmware for your camera. Will try and connect with HTTP. Camera at IP:192.168.1.200, fault was com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: Bad response: (404Not Found
10:29:47.353 [ERROR] [ml.internal.messaging.saaj.client.p2p] - SAAJ0008: Bad Response; Not Found
10:29:47.353 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - SOAP error when trying to connect with ONVIF. This may indicate your camera does not fully support ONVIF, check for an updated firmware for your camera. Will try and connect with HTTP. Camera at IP:192.168.1.202, fault was com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: Bad response: (404Not Found
10:29:47.356 [ERROR] [ml.internal.messaging.saaj.client.p2p] - SAAJ0008: Bad Response; Not Found
10:29:47.356 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - SOAP error when trying to connect with ONVIF. This may indicate your camera does not fully support ONVIF, check for an updated firmware for your camera. Will try and connect with HTTP. Camera at IP:192.168.1.201, fault was com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: com.sun.xml.internal.messaging.saaj.SOAPExceptionImpl: Bad response: (404Not Found
10:29:47.359 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IpCamera file server for camera 192.168.1.200 has started on port 50001
10:29:47.359 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IP Camera at 192.168.1.200 is now online.
10:29:47.359 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IpCamera file server for camera 192.168.1.203 has started on port 50004
10:29:47.359 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IP Camera at 192.168.1.203 is now online.
10:29:47.359 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:North_Yard' changed from INITIALIZING to ONLINE
10:29:47.360 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IpCamera file server for camera 192.168.1.202 has started on port 50003
10:29:47.360 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IP Camera at 192.168.1.202 is now online.
10:29:47.360 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Carport' changed from INITIALIZING to ONLINE
10:29:47.360 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:Driveway' changed from INITIALIZING to ONLINE
10:29:47.379 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IpCamera file server for camera 192.168.1.201 has started on port 50002
10:29:47.380 [INFO ] [ding.ipcamera.handler.IpCameraHandler] - IP Camera at 192.168.1.201 is now online.
10:29:47.380 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'ipcamera:HIKVISION:South_Steps' changed from INITIALIZING to ONLINE
10:29:49.740 [WARN ] [ding.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel       URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
io.netty.util.concurrent.BlockingOperationException: DefaultChannelPromise@23b31a3c(incomplete)
        at io.netty.util.concurrent.DefaultPromise.checkDeadLock(DefaultPromise.java:386) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.checkDeadLock(DefaultChannelPromise.java:159) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:236) ~[235:io.netty.common:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:137) ~[237:io.netty.transport:4.1.34.Final]
        at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:30) ~[237:io.netty.transport:4.1.34.Final]

matt1 · August 18, 2019, 1:16pm

Provide some trace level log with only one camera and I’ll take a look at what is going on. Must be trace and only one camera.

rednep · August 18, 2019, 11:59pm

Hi Matt. Great binding. Installed over the weekend and configured a Foscam FI9816P. Seems to be working well (pan, tilt, motion detection, image in PaperUI) Now to start configuring rules.

Hi Everyone. If buying new cameras, what are people recommending to work with the binding? Thanks

matt1 · August 19, 2019, 12:31am

Glad you like the binding.

For which brands work best and what features are implemented for each brand please see the first post of this thread here…

Also for camera recommendations best to ask in a camera form like this one, plenty of threads already discussing which camera is best for which application.

dastrix80 · August 22, 2019, 5:56am

Hi Matt

See attached debug, only 1 camera is configured. Latest binding version
It seems authentication related, but ive tried the admin crendentials and an operator user, same issue. Its set to digest/basic, the other option is digest only.

Hopefully this helps:


:
2019-08-22 15:50:19.832 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.832 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.833 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.833 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=82
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="5a44466c5a6d4535597a7069595441314e5749354e773d3d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.834 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.834 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.836 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.836 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.837 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.837 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.837 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.838 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=81
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4f5445774e4446694e6a5536596d45774e5456694f57493d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.838 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.839 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.841 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.841 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.842 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.842 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.842 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.842 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.843 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.843 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.843 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=80
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4d5449354e575a694f575936596d45774e5456695954413d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.843 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.844 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.844 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.844 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.844 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=15
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4f5745785a5468695a546b36596d45774e5456694f57513d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.845 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.845 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.848 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.849 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.849 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.849 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.849 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.849 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.850 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.850 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.850 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=14
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4d574e6b4e32453559324d36596d45774e5456695954673d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.850 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.850 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.851 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.851 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.851 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=79
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4f546c694e446469596d4d36596d45774e5456695954633d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.852 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.852 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.854 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.854 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.854 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.855 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.855 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.855 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.855 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.855 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.856 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=78
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4e44566a5932493159324536596d45774e5456695957513d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.856 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.856 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.856 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.856 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.857 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=13
WWW-Authenticate: Digest qop="auth", realm="SéH", nonce="4f546c6c4f5749354e325936596d45774e54566959574d3d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:50:19.857 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 538, widx: 538, cap: 1024)), decoderResult: success)
2019-08-22 15:50:19.857 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:50:19.859 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.859 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.859 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.859 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - 401: Mark to re-use,  the channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:50:19.860 [DEBUG] [ipcamera.internal.MyNettyAuthHandler] - Setting up the camera to use Basic Auth and resending last request with correct auth.
2019-08-22 15:50:19.860 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:1    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:50:19.860 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - Sending camera: GET: http://192.168.1.200/ISAPI/Event/notification/alertStream
2019-08-22 15:50:37.815 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.io.monitor.internal.EventLogger@5e9d07e0' takes more than 5000ms.
2019-08-22 15:50:32.809 [INFO ] [.smarthome.model.script.system.rules] - Server Uptime updated to 6 Days / 22 Hours / 17 Min.
2019-08-22 15:50:19.860 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:51:19.863 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=12
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="59574e6c4d7a63794f545136596d45774e545669596a493d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:51:19.863 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - commonCameraHandler closed channel:3   URL:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:51:19.863 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:51:19.864 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - commonCameraHandler closed channel:4   URL:/ISAPI/Event/notification/alertStream
2019-08-22 15:51:19.864 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:
2019-08-22 15:51:19.864 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] -    Using the already open channel:2    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:51:19.864 [TRACE] [ipcamera.internal.MyNettyAuthHandler] - MyNettyAuthHandler is now setup for    GET:/ISAPI/Event/notification/alertStream
2019-08-22 15:51:19.872 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultHttpResponse(decodeResult: success, version: HTTP/1.1)
HTTP/1.1 401 Unauthorized
Date: Thu, 22 Aug 2019 15:50:08 GMT
Server: App-webs/
Content-Length: 178
Content-Type: text/html
Connection: keep-alive
Keep-Alive: timeout=10, max=77
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)", nonce="4e4451304f5463344d546336596d45774e545669596a4d3d", stale="FALSE"
WWW-Authenticate: Basic realm="IP Camera(76898)"
2019-08-22 15:51:19.872 [DEBUG] [ing.ipcamera.handler.IpCameraHandler] - commonCameraHandler closed channel:1   URL:/ISAPI/Streaming/channels/101/picture
2019-08-22 15:51:19.872 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - DefaultLastHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 178, cap: 178/178, unwrapped: PooledUnsafeDirectByteBuf(ridx: 551, widx: 551, cap: 1024)), decoderResult: success)
2019-08-22 15:51:19.872 [TRACE] [ing.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is        :<!DOCTYPE html>
<html><head><title>Document Error: Unauthorized</title></head>
<body><h2>Access Error: 401 -- Unauthorized</h2>
<p>Authentication Error</p>
</body>
</html>
:

matt1 · August 22, 2019, 9:43am

It did not show any problems so check this…

In cameras settings make sure “Enable Illegal Login Lock” is unticked, save and then reboot the camera. Also make sure the IP filter is not setup.

I have multiple HIk cameras and have one setup for basic and the other for digest and they just work.

I did notice this weird thing in the logs which to my knowledge a camera should not change the realm. It may be a bug in the cameras firmware that has triggered the illegal lock feature.

WWW-Authenticate: Digest qop="auth", realm="SéH",
WWW-Authenticate: Digest qop="auth", realm="IP Camera(76898)",

Also it must be login details for admin rights, user level is not enough.

dastrix80 · August 22, 2019, 9:59am

I unticked the enable illegal login, ip filter was off. Changed to digest for shits and giggles, no joy

19:53:15.842 [ERROR] [ml.internal.messaging.saaj.client.p2p] - SAAJ0008: Bad Response; Not Found

Definitely back onto admin credentials.

matt1 · August 22, 2019, 10:22am

That error is from ONVIF and is not needed for the camera to work, you can ignore that one for now. Install the ‘Dlink smart home binding’ as that will install any missing dependancies for the onvif if there is an issue with that side of things.

dezito · August 22, 2019, 11:59am

Continuing the discussion from IpCamera: New IP Camera Binding:

When i connect my hikvision wifi camera, after some time openhab crashes because of “java.lang.OutOfMemoryError: Java heap space” its the ipcamera:HIKVISION:IpCamHoved that is the problem. When i remove the ipcamera:HIKVISION:IpCamHoved from the things the problem goes away.

Camera info:
DS-2CD2532F-IWS
Firmware V5.4.5 build 170123
LOG:

2019-08-22 04:05:55.122 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while handling, caused by java.net.SocketException: Kanalen blev brudt (Write failed)
2019-08-22 04:05:55.123 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while reading, caused by javax.net.ssl.SSLException: Connection has been shutdown: javax.net.ssl.SSLException: java.net.SocketException: Kanalen blev brudt (Write failed)
2019-08-22 04:05:55.123 [WARN ] [su.litvak.chromecast.api.v2.Channel ] -  <--  null payload in message 
2019-08-22 04:05:56.487 [WARN ] [su.litvak.chromecast.api.v2.Channel ] - Error while sending 'PING', caused by java.net.SocketException: Socket is closed
2019-08-22 04:06:12.999 [WARN ] [io.netty.channel.nio.NioEventLoop   ] - Unexpected exception in the selector loop.
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:06:30.361 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@72f37bc7' takes more than 5000ms.
2019-08-22 04:07:03.881 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel 	URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:07:10.697 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel 	URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:07:25.219 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@72f37bc7' takes more than 5000ms.
2019-08-22 04:07:34.797 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@72f37bc7' takes more than 5000ms.
2019-08-22 04:07:34.806 [INFO ] [io.openhabcloud.internal.CloudClient] - Disconnected from the openHAB Cloud service (UUID = 205093e4-4f07-4ab3-b3d4-c62db9f28b27, base URL = http://localhost:8080)
2019-08-22 04:10:57.966 [WARN ] [me.core.internal.events.EventHandler] - Dispatching event to subscriber 'org.eclipse.smarthome.core.internal.items.ItemUpdater@72f37bc7' takes more than 5000ms.
2019-08-22 04:10:57.968 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:10:57.970 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@2601c8f5 in QueuedThreadPool[ServletModel-12]@2dc7e85{STARTED,8<=8<=8,i=6,r=1,q=0}[ReservedThreadExecutor@28c63245{s=0/1,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:18.847 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel 	URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.801 [WARN ] [io.netty.channel.nio.NioEventLoop   ] - Unexpected exception in the selector loop.
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:10:55.289 [ERROR] [org.quartz.core.JobRunShell         ] - Job MapDB_SchedulerGroup.Commit_Transaction threw an unhandled Exception: 
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:18.847 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@39ff61d5 in QueuedThreadPool[ESH-httpClient-common]@367224d1{STARTED,10<=10<=40,i=4,r=4,q=0}[ReservedThreadExecutor@8b5bd2b{s=1/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.801 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@7382b18b in QueuedThreadPool[qtp893194427]@353d10bb{STARTED,2<=8<=50,i=2,r=4,q=0}[ReservedThreadExecutor@7ee5fbeb{s=1/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.801 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@2f1ae739 in QueuedThreadPool[HttpClient@178df92c]@311a5c0e{STARTED,8<=8<=200,i=4,r=4,q=0}[ReservedThreadExecutor@ab181ec{s=1/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.800 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@2601c8f5 in QueuedThreadPool[ServletModel-12]@2dc7e85{STARTED,8<=8<=8,i=6,r=1,q=0}[ReservedThreadExecutor@28c63245{s=0/1,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:10:59.423 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.801 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@2f1ae739 in QueuedThreadPool[HttpClient@178df92c]@311a5c0e{STARTED,8<=8<=200,i=4,r=4,q=0}[ReservedThreadExecutor@ab181ec{s=1/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:37.481 [ERROR] [org.quartz.core.ErrorLogger         ] - Job (MapDB_SchedulerGroup.Commit_Transaction threw an exception.
org.quartz.SchedulerException: Job threw an unhandled exception.
	at org.quartz.core.JobRunShell.run(JobRunShell.java:213) [181:org.openhab.core.scheduler:2.5.0.201908180304]
	at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573) [181:org.openhab.core.scheduler:2.5.0.201908180304]
Caused by: java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.802 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@2f1ae739 in QueuedThreadPool[HttpClient@178df92c]@311a5c0e{STARTED,8<=8<=200,i=4,r=4,q=0}[ReservedThreadExecutor@ab181ec{s=1/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:14.802 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:36.113 [ERROR] [org.apache.felix.fileinstall        ] - In main loop, we have serious trouble
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:36.112 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - Unexpected thread death: org.eclipse.jetty.util.thread.QueuedThreadPool$Runner@32cf7812 in QueuedThreadPool[HttpClient@3a58bca]@42ccd0e7{STARTED,8<=8<=200,i=6,r=4,q=0}[ReservedThreadExecutor@3c08803b{s=0/4,p=0}]
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:36.112 [ERROR] [org.apache.felix.fileinstall        ] - In main loop, we have serious trouble
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:34.764 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - !!!! Camera has closed the channel 	URL:/ISAPI/Streaming/channels/101/picture Cause reported is: {}
java.lang.OutOfMemoryError: Java heap space
2019-08-22 04:12:56.738 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - The alarm stream was not running for camera 10.0.0.91, re-starting it now
2019-08-22 04:12:59.502 [WARN ] [WaveSerialHandler$ZWaveReceiveThread] - Exception during ZWave thread. 
java.lang.IllegalStateException: Queue full
	at java.util.AbstractQueue.add(AbstractQueue.java:98) ~[?:?]
	at java.util.concurrent.ArrayBlockingQueue.add(ArrayBlockingQueue.java:312) ~[?:?]
	at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager.processReceiveMessage(ZWaveTransactionManager.java:410) ~[271:org.openhab.binding.zwave:2.5.0.201908182309]
	at org.openhab.binding.zwave.internal.protocol.ZWaveController.incomingPacket(ZWaveController.java:1064) ~[271:org.openhab.binding.zwave:2.5.0.201908182309]
	at org.openhab.binding.zwave.handler.ZWaveControllerHandler.incomingMessage(ZWaveControllerHandler.java:411) ~[271:org.openhab.binding.zwave:2.5.0.201908182309]
	at org.openhab.binding.zwave.handler.ZWaveSerialHandler$ZWaveReceiveThread.run(ZWaveSerialHandler.java:331) [271:org.openhab.binding.zwave:2.5.0.201908182309]
2019-08-22 04:13:00.016 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:00.017 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Cleaning the channels has just found a connection with wrong open state.
2019-08-22 04:13:00.019 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler 		URL:/ISAPI/System/IO/inputs/1/status
2019-08-22 04:13:00.175 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 14 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:00.254 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 14 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:00.331 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 15 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:00.332 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Cleaning the channels has just found a connection with wrong open state.
2019-08-22 04:13:00.332 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler 		URL:/ISAPI/System/IO/inputs/1/status
2019-08-22 04:13:00.418 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:03.717 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:03.954 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:04.698 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:05.162 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:07.521 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:08.643 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:09.121 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:11.925 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:15.242 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:15.464 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:15.545 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:15.620 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 14 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:15.621 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Cleaning the channels has just found a connection with wrong open state.
2019-08-22 04:13:15.622 [WARN ] [ing.ipcamera.handler.IpCameraHandler] - Can't find ch when removing handler 		URL:/ISAPI/System/IO/inputs/1/status
2019-08-22 04:13:17.140 [INFO ] [ing.ipcamera.handler.IpCameraHandler] - There are 13 channels being tracked, cleaning out old channels now to try and reduce this to 12 or below.
2019-08-22 04:13:37.480 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{FAILED,8<=0<=200,i=0,r=-1,q=6}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$166/2135414754@3f6d1e1a
2019-08-22 04:15:07.672 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19380,5,main]
2019-08-22 04:15:07.677 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19369,5,main]
2019-08-22 04:15:07.678 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19372,5,main]
2019-08-22 04:15:07.678 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19382,5,main]
2019-08-22 04:15:07.679 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19373,5,main]
2019-08-22 04:15:07.682 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19374,5,main]
2019-08-22 04:15:07.683 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=7<=200,i=0,r=-1,q=9}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19379,5,main]
2019-08-22 04:16:07.694 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPED,8<=0<=200,i=0,r=-1,q=0}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$166/2135414754@359a795d
2019-08-22 04:38:25.819 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{FAILED,8<=0<=200,i=0,r=-1,q=27}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$166/2135414754@3537bb5f
2019-08-22 04:39:56.121 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19624,5,main]
2019-08-22 04:39:56.122 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19620,5,main]
2019-08-22 04:39:56.122 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19621,5,main]
2019-08-22 04:39:56.124 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19616,5,main]
2019-08-22 04:39:56.125 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19618,5,main]
2019-08-22 04:39:56.126 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19626,5,main]
2019-08-22 04:39:56.126 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19628,5,main]
2019-08-22 04:39:56.128 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19632,5,main]
2019-08-22 04:39:56.128 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19615,5,main]
2019-08-22 04:39:56.129 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19630,5,main]
2019-08-22 04:39:56.130 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19629,5,main]
2019-08-22 04:39:56.132 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19619,5,main]
2019-08-22 04:39:56.133 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19631,5,main]
2019-08-22 04:39:56.134 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19617,5,main]
2019-08-22 04:39:56.135 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19623,5,main]
2019-08-22 04:39:56.136 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19627,5,main]
2019-08-22 04:39:56.137 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19625,5,main]
2019-08-22 04:39:56.138 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPING,8<=18<=200,i=0,r=-1,q=21}[NO_TRY] Couldn't stop Thread[HttpClient@366630bb-19622,5,main]
2019-08-22 04:40:56.150 [WARN ] [e.jetty.util.thread.QueuedThreadPool] - QueuedThreadPool[HttpClient@366630bb]@32c5ac30{STOPPED,8<=0<=200,i=0,r=-1,q=0}[NO_TRY] rejected org.eclipse.jetty.io.ManagedSelector$$Lambda$166/2135414754@52ab0045

Thing file:

Thing ipcamera:HIKVISION:IpCamGarage "IpCam Garage" @ "Cameras"
[
    IPADDRESS="10.0.0.90",
    PORT=80,
    PASSWORD="",
    USERNAME="admin",
    POLL_CAMERA_MS=2000,
    SERVER_PORT=50001,
    ONVIF_PORT=9999,
    IP_WHITELIST="DISABLE",
    IMAGE_UPDATE_EVENTS=1,
    UPDATE_IMAGE=true,
    GIF_PREROLL=20,
    GIF_POSTROLL=40,
    FFMPEG_OUTPUT="/tmp/IpCamGarage/",
    FFMPEG_INPUT="rtsp://10.0.0.90:554/Streaming/Channels/102/"
]
Thing ipcamera:HIKVISION:IpCamHoved "IpCam Hoveddør" @ "Cameras"
[
    IPADDRESS="10.0.0.91",
    PORT=80,
    PASSWORD="",
    USERNAME="admin",
    POLL_CAMERA_MS=2000,
    SERVER_PORT=50002,
    ONVIF_PORT=9999,
    IP_WHITELIST="DISABLE",
    IMAGE_UPDATE_EVENTS=1,
    UPDATE_IMAGE=true,
    GIF_PREROLL=20,
    GIF_POSTROLL=40,
    FFMPEG_OUTPUT="/tmp/IpCamHoved/",
    FFMPEG_INPUT="rtsp://10.0.0.91:554/Streaming/Channels/102/"
]

Item file:

Image IpCamGarageImage "Garage" (gCamera) { channel="ipcamera:HIKVISION:IpCamGarage:image" }
Switch IpCamGarageUpdateImage "Garage" { channel="ipcamera:HIKVISION:IpCamGarage:updateImageNow" }
Switch IpCamGarageCreateGif "Garage" (gCameraGif) { channel="ipcamera:HIKVISION:IpCamGarage:updateGif" }
String IpCamGarageStreamUrl "Garage" { channel="ipcamera:HIKVISION:IpCamGarage:streamUrl" }

Image IpCamHovedImage "Hoveddør" (gCamera) { channel="ipcamera:HIKVISION:IpCamHoved:image" }
Switch IpCamHovedUpdateImage "Hoveddør" { channel="ipcamera:HIKVISION:IpCamHoved:updateImageNow" }
Switch IpCamHovedCreateGif "Hoveddør" (gCameraGif) { channel="ipcamera:HIKVISION:IpCamHoved:updateGif" }
String IpCamHovedStreamUrl "Hoveddør" { channel="ipcamera:HIKVISION:IpCamHoved:streamUrl" }```

dastrix80 · August 22, 2019, 9:12pm

Thanks, no change, errors persist

And the URL for the camera should be http://192.168.1.3:50001/Streaming/channels/102/httppreview right?

Thats my OH2 IP

some of the latest debug:


07:16:31.680 [TRACE] [ding.ipcamera.handler.IpCameraHandler] - DefaultHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 603, cap: 603/603, unwrapped: PooledUnsafeDirectByteBuf(ridx: 603, widx: 603, cap: 2048)), decoderResult: success)
07:16:31.682 [TRACE] [ding.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is  :--boundary
Content-Type: application/xml; charset="UTF-8"
Content-Length: 520

<EventNotificationAlert version="2.0" xmlns="http://www.hikvision.com/ver20/XMLSchema">
<ipAddress>192.168.1.200</ipAddress>
<portNo>80</portNo>
<protocol>HTTP</protocol>
<macAddress>18:68:cb:b4:13:92</macAddress>
<channelID>1</channelID>
<dateTime>2019-08-23T07:16:19+09:00</dateTime>
<activePostCount>0</activePostCount>
<eventType>videoloss</eventType>
<eventState>inactive</eventState>
<eventDescription>videoloss alarm</eventDescription>
<channelName>North Yard</channelName>
</EventNotificationAlert>
:
07:16:41.589 [TRACE] [ding.ipcamera.handler.IpCameraHandler] - DefaultHttpContent(data: PooledSlicedByteBuf(ridx: 0, widx: 603, cap: 603/603, unwrapped: PooledUnsafeDirectByteBuf(ridx: 603, widx: 603, cap: 2048)), decoderResult: success)
07:16:41.590 [TRACE] [ding.ipcamera.handler.IpCameraHandler] - HTTP Result back from camera is  :--boundary
Content-Type: application/xml; charset="UTF-8"
Content-Length: 520

<EventNotificationAlert version="2.0" xmlns="http://www.hikvision.com/ver20/XMLSchema">
<ipAddress>192.168.1.200</ipAddress>
<portNo>80</portNo>
<protocol>HTTP</protocol>
<macAddress>18:68:cb:b4:13:92</macAddress>
<channelID>1</channelID>
<dateTime>2019-08-23T07:16:29+09:00</dateTime>
<activePostCount>0</activePostCount>
<eventType>videoloss</eventType>
<eventState>inactive</eventState>
<eventDescription>videoloss alarm</eventDescription>
<channelName>North Yard</channelName>
</EventNotificationAlert>
:

TRS-80 · August 22, 2019, 11:19pm

Great work, @matt1!

I have a few of the cheap Xiaomi cameras on the way from AliExpress, looking forward to testing with this when they come in!

matt1 · August 23, 2019, 5:23am

There are clearly changes, the authorisation is now working.

No, try http://192.168.1.3:50001/ipcamera.jpg

The readme file covers this in depth, does it need changing/updating? If so which part is in need of reworking?

@dezito

Try searching the forum before posting, this thread should go into detail as will searching this thread…