Heiman DX1CG-Z CH4 gas sensor won't announce alarm

Hi,
recently I bought combustible gas sensor (DX1CG-Z CH4) with Heiman brand. It was added to the network and recognized by OH2.

I test it with gas lighter and the sound alarm is triggered, but nothing on z-wave side. I don’t see anything in the debug logs. It’s pretty simple sensor i.e. one channel, no association, no configuration parameters. I also push the test button that also doesn’t do anything on z-wave side

Does anyone has experience with this sensor?

cheers.
W

Please provide a debug log showing what is being sent when there is an alarm. IF nothing is sent, then probably the associations aren’t configured to send the alarms.

I see nothing in the zwave logs and Karaf is set to debug level. I’ve removed it from the network and added it. Also deleted the thing and added it again, but none of these helped. My zwave controller is Aeon zstick Gen5.

What do you mean that associations are not configured? In habmin I don’t see any association groups available…

Associations are the mechanism that ZWave uses to tell a device to send data to another device. If they are not configured, then devices will not send data unless they are polled (which will add huge latency).

What version of the binding are you using? If you’re not on the latest snapshot (or the 2.4 M4 build) then I would suggest to try this.

If you are already on this binding, then if you provide a log of the device initialisation I’ll take a look. There is an option in the HABmin thing menu to reinitialise the device - please grab the debug log showing the transactions that occur after this (you will need to wake up the device once you do the reinitialisation).

I’m still on M3 and didn’t had the time to move to M4. Maybe in the next days I’ll do that.

What makes you think that moving to M4 will help me? As for the associations I don’t see any defined for this device?.. or there are ‘hidden’ one that don’y show up in OH2?

It’s got a lot of changes. M3 and earlier has not been changed for a while. Please see the link I posted above - this is just the major changes, but even there you can see changes to the associations.

If it’s a ZWave plus device, then the binding should sort this out itself with the latest binding. The old binding will do nothing and will therefore not work.

Thanks for the info. I’ll report back in the next days when I find some time to upgrade.

@chris
I’ve find the time to move from M3 to M4. Was a bit confusing in openhabian to do this switch as I expected this would be the snapshot build, but that resulted to M3 again. Instead it turned out that I needed to switch to testing build that got all M4 OH artifacts.

The move was not uneventful. As per instructions I deleted the things (except the zstick) switched to M4 and then started the discovery. The things showed up in the inbox and I re-added them back. So far so good.

For some reason after this operation OH was not working as I’ve expected. I could see that there is some zwave traffic. The problem was that for some reason it didn’t had connection with the UI elements. For instance in Basic UI I press a switch and it changes its state, but in the logs I didn’t see that item changed its state, respectively nothing happened on zwave side.

At that point I didn’t had time and abandoned for a while the upgrade until yesterday. I again run the latest upgrade/update and re-enabled the logviewer app that for some reason was removed. After this procedure things started working again. I don’t know what happened, I guess some zwave traffic but commands were executed with some delay, sometimes reaching 30sec.

Then when adding new switch to the system all of a sudden in the Inbox I got most of the nodes discovered for second time?!..but not resolved to a known thing (unknown device). I deleted them from the inbox

As for the alarm it already started appearing in the zwave log, but for some reason the UI was not updating…or I expected to indicate that alarm went off some how. For alarm type item in the UI have these two buttons OK and ALARM and neither of them is changing state. In the zwave debug logs I see this

2018-10-18 09:04:55.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2018-10-18 09:04:55.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE)
2018-10-18 09:04:55.256 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false
2018-10-18 09:04:55.262 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2018-10-18 09:04:55.267 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported
2018-10-18 09:04:55.273 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_ALARM V7 NOTIFICATION_REPORT
2018-10-18 09:04:55.278 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 19: NOTIFICATION report - 0 = 0, event=2, status=255, plen=0
2018-10-18 09:04:55.284 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 19: Alarm Type = null (0)
2018-10-18 09:04:55.288 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2018-10-18 09:04:55.294 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint = 0, comma
nd class = COMMAND_CLASS_ALARM, value = 255
2018-10-18 09:04:55.297 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 19: Alarm converter processing NOTIFICATION
2018-10-18 09:04:55.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1.
2018-10-18 09:04:55.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protoc
ol.ZWaveCommandClassPayload@1a244fa.
2018-10-18 09:04:55.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-18 09:04:55.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2018-10-18 09:04:55.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty

So how the alarm should be indicated in the UI when it goes off?

For some reason I have problem with one of the Temp/Humidity sensors of Heimen. Now it can’t be resolved to a known device, regardless how many times I woke up the device and from the habmin I set the device to be initalized. Is it that this NIF frame is transmitted only when device is initialized? I mean is it only then when the vendor/device ID is sent?

p.s. the new version of the plugin is really chatty compared to the previous one. Logs are growing really quick in debug mode

cheers,
W

I forgot one thing. I started seeing often these (in debug)
2018-10-18 15:46:04.853 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol > error (OOF). Got 0x00.

2018-10-18 15:46:04.849 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xC6.
2018-10-18 15:46:04.873 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x5D.
2018-10-18 15:46:04.873 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x19.
2018-10-18 15:46:04.877 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x46.
2018-10-18 15:46:04.877 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xBC.
2018-10-18 15:46:04.881 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x10.
2018-10-18 15:46:04.881 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:04.884 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:04.885 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04.
2018-10-18 15:46:05.137 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Timeout - Sending NAK
2018-10-18 15:46:06.346 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x10.
2018-10-18 15:46:06.346 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:06.349 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:06.350 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04.
2018-10-18 15:46:06.602 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Timeout - Sending NAK
2018-10-18 15:46:07.846 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:07.846 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x10.
2018-10-18 15:46:07.850 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:07.850 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x04.
2018-10-18 15:46:07.854 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x16.
2018-10-18 15:46:07.855 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 32 02 A1 42 5C C6 00 19 5D 46
2018-10-18 15:46:07.859 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xBC.
2018-10-18 15:46:07.860 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2018-10-18 15:46:07.865 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Frame length is out of limits (0)
2018-10-18 15:46:07.865 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x10.
2018-10-18 15:46:07.869 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 00 16 0A 32
2018-10-18 15:46:07.871 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x02.
2018-10-18 15:46:07.873 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Message is invalid, discarding
2018-10-18 15:46:07.875 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0xA1.
2018-10-18 15:46:07.879 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x4A.
2018-10-18 15:46:07.879 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x02.
2018-10-18 15:46:07.883 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x05.
2018-10-18 15:46:07.883 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2018-10-18 15:46:07.886 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x19.
2018-10-18 15:46:07.886 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.
2018-10-18 15:46:07.890 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x32.
2018-10-18 15:46:07.890 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Protocol error (OOF). Got 0x00.

I don’t know if I should consider them a communication problem or it’s ok?

Hi,

I am facing the same problem so it would be interesting to know if you manage to resolve the issue with Heiman DX1CG-Z CH4 sensor?

Thanks
J

What is your setup? What version of the binding are you using? If it’s not the latest snapshot (or at least the 2.5M1 version) then please update.

Hi @Janko_Blatnik I gave up with this sensor. I did’t had the time to spend hours chasing ghosts. Hopefully you have better luck.
These Heimen sensors are half the price but kind of problematic. The humidity sensor for instance was drainig the battery (large coin) for 2-3 weeks. The gas alarm I never managed to make it work. Perhaps the flood sensor was the only one working.
I like OH that you can do lots of things, but as typical OSS project you need to invest time.
The good thing is that my setup using Aeon gen5+Raspberry+Openhabian is working for the past 6 months. I didn’t do updates, as I didn’t need new stuff and it’s still working fine. Well, rules are not really working after reboot…but that’s another story:)

Hi Chris,

Thanks for your reply. I am just testing the Heiman DX1CG-Z CH4 gas sensor and it doesn’t announce an alarm (in openHab 2.4 and neither in 2.5M1 and neither in 2.5.0-Snapshot)

Any idea what to do?

Thanks

Janko

Ensure that the associations are set - they should be as the database is configured to set group 0.

Then trigger the alarm and get a debug log so we can see what is happening.

Hi Chris,

I attachment you may see the opehab log file and z-wave configuration files for Gen5 Z-stick and Heiman DX1CG-Z CH4 gas sensor.

Thanks for your help.

(Attachment openhab.log is missing)

network_e6d11695__node_1.xml (1.77 KB)

network_e6d11695__node_2.xml (6.61 KB)

Resending also the log file.

(Attachment openhab.log is missing)

Hi,

Since I am having problems sending you log files, I am sending you the logs inline:

When ever I press a test button on DX1CG-Z CH4 sensor or I trigger it with the gas, I do get a new event in a event.log:

2019-04-21 02:17:51.365 [vent.ItemStateChangedEvent] - ZWaveSerialController_StartFrames changed from 386 to 387

and in openhab.log I am getting the following records:

2019-04-21 02:17:51.365 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@49c443ea[p=0,l=167,c=32768,r=167]={<<<event: message\nda…mStateEvent"}\n\n>>>\",\“oldValue\”:\…umber",“kind”:"} complete=false committing=false callback=Blocker@40f6a539{null}
2019-04-21 02:17:51.365 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@76b65ed1[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@188493a1] generate: FLUSH (null,[p=0,l=167,c=32768,r=167],false)@COMMITTED
2019-04-21 02:17:51.365 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@2d26b3c9{IDLE}->null [HeapByteBuffer@2ab80e08[p=0,l=6,c=1024,r=6]={<<<\r\nA7\r\n>>>\n\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@49c443ea[p=0,l=167,c=32768,r=167]={<<<event: message\nda…mStateEvent"}\n\n>>>\",\“oldValue\”:\…umber",“kind”:"}]
2019-04-21 02:17:51.365 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@2d26b3c9{WRITING}->null:IDLE–>WRITING
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 173 SocketChannelEndPoint@2bd59222{/0:0:0:0:0:0:0:1:63949<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=6753/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1a0e98d6[p=HttpParser{s=END,0 of -1},g=HttpGenerator@1dcd4d37{s=COMMITTED}]=>HttpChannelOverHttp@42473f5f{r=11,c=true,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=15987316}
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=173 remaining=0 WriteFlusher@2d26b3c9{WRITING}->null
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@2d26b3c9{IDLE}->null:WRITING–>IDLE
2019-04-21 02:17:51.366 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@76b65ed1[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@188493a1] generate: DONE (null,[p=167,l=167,c=32768,r=0],false)@COMMITTED
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@e6d5fcf[p=0,l=167,c=32768,r=167]={<<<event: message\nda…mStateEvent"}\n\n>>>\",\“oldValue\”:\…03ch1\u003eOver} complete=false committing=false callback=Blocker@2d7f28b5{null}
2019-04-21 02:17:51.366 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@6f463b00[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@5800def8] generate: FLUSH (null,[p=0,l=167,c=32768,r=167],false)@COMMITTED
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@7ae08fc2{IDLE}->null [HeapByteBuffer@5966cf9f[p=0,l=6,c=1024,r=6]={<<<\r\nA7\r\n>>>\n\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@e6d5fcf[p=0,l=167,c=32768,r=167]={<<<event: message\nda…mStateEvent"}\n\n>>>\",\“oldValue\”:\…03ch1\u003eOver}]
2019-04-21 02:17:51.366 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@7ae08fc2{WRITING}->null:IDLE–>WRITING
2019-04-21 02:17:51.367 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 173 SocketChannelEndPoint@338178d2{/0:0:0:0:0:0:0:1:52898<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=6753/30000}{io=0/0,kio=0,kro=1}->HttpConnection@3800e416[p=HttpParser{s=END,0 of -1},g=HttpGenerator@7f5c2c{s=COMMITTED}]=>HttpChannelOverHttp@5d52f6f8{r=10,c=true,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=14505791}
2019-04-21 02:17:51.367 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=173 remaining=0 WriteFlusher@7ae08fc2{WRITING}->null
2019-04-21 02:17:51.367 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@7ae08fc2{IDLE}->null:WRITING–>IDLE
2019-04-21 02:17:51.367 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@6f463b00[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@5800def8] generate: DONE (null,[p=167,l=167,c=32768,r=0],false)@COMMITTED
2019-04-21 02:17:51.367 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@49c443ea[p=0,l=226,c=32768,r=226]={<<<event: message\nda…hangedEvent"}\n\n>>>ent"}\n\noupNames\"…umber",“kind”:"} complete=false committing=false callback=Blocker@40f6a539{null}
2019-04-21 02:17:51.367 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@76b65ed1[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@5bd86908] generate: FLUSH (null,[p=0,l=226,c=32768,r=226],false)@COMMITTED
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@2d26b3c9{IDLE}->null [HeapByteBuffer@2ab80e08[p=0,l=6,c=1024,r=6]={<<<\r\nE2\r\n>>>\n\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@49c443ea[p=0,l=226,c=32768,r=226]={<<<event: message\nda…hangedEvent"}\n\n>>>ent"}\n\noupNames\"…umber",“kind”:"}]
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@2d26b3c9{WRITING}->null:IDLE–>WRITING
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 232 SocketChannelEndPoint@2bd59222{/0:0:0:0:0:0:0:1:63949<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@1a0e98d6[p=HttpParser{s=END,0 of -1},g=HttpGenerator@1dcd4d37{s=COMMITTED}]=>HttpChannelOverHttp@42473f5f{r=11,c=true,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=15987318}
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=232 remaining=0 WriteFlusher@2d26b3c9{WRITING}->null
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@2d26b3c9{IDLE}->null:WRITING–>IDLE
2019-04-21 02:17:51.368 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@76b65ed1[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@5bd86908] generate: DONE (null,[p=226,l=226,c=32768,r=0],false)@COMMITTED
2019-04-21 02:17:51.368 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@e6d5fcf[p=0,l=226,c=32768,r=226]={<<<event: message\nda…hangedEvent"}\n\n>>>ent"}\n\noupNames\"…03ch1\u003eOver} complete=false committing=false callback=Blocker@2d7f28b5{null}
2019-04-21 02:17:51.369 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@6f463b00[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@37db9336] generate: FLUSH (null,[p=0,l=226,c=32768,r=226],false)@COMMITTED
2019-04-21 02:17:51.369 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@7ae08fc2{IDLE}->null [HeapByteBuffer@5966cf9f[p=0,l=6,c=1024,r=6]={<<<\r\nE2\r\n>>>\n\n\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@e6d5fcf[p=0,l=226,c=32768,r=226]={<<<event: message\nda…hangedEvent"}\n\n>>>ent"}\n\noupNames\"…03ch1\u003eOver}]
2019-04-21 02:17:51.369 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@7ae08fc2{WRITING}->null:IDLE–>WRITING
2019-04-21 02:17:51.369 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 232 SocketChannelEndPoint@338178d2{/0:0:0:0:0:0:0:1:52898<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=2/30000}{io=0/0,kio=0,kro=1}->HttpConnection@3800e416[p=HttpParser{s=END,0 of -1},g=HttpGenerator@7f5c2c{s=COMMITTED}]=>HttpChannelOverHttp@5d52f6f8{r=10,c=true,a=ASYNC_WAIT,uri=//localhost:8080/rest/events,age=14505793}
2019-04-21 02:17:51.369 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=232 remaining=0 WriteFlusher@7ae08fc2{WRITING}->null
2019-04-21 02:17:51.369 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@7ae08fc2{IDLE}->null:WRITING–>IDLE
2019-04-21 02:17:51.369 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@6f463b00[PROCESSING][i=null,cb=org.eclipse.jetty.server.HttpChannel$ContentCallback@37db9336] generate: DONE (null,[p=226,l=226,c=32768,r=0],false)@COMMITTED
2019-04-21 02:17:53.418 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@e346a92{/0:0:0:0:0:0:0:1:64608<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=29377/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a155385[p=HttpParser{s=END,0 of -1},g=HttpGenerator@67d8c0af{s=START}]=>HttpChannelOverHttp@267e1443{r=4,c=false,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=29374} idle timeout check, elapsed: 29377 ms, remaining: 623 ms
2019-04-21 02:17:54.042 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@e346a92{/0:0:0:0:0:0:0:1:64608<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30001/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a155385[p=HttpParser{s=END,0 of -1},g=HttpGenerator@67d8c0af{s=START}]=>HttpChannelOverHttp@267e1443{r=4,c=false,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=29998} idle timeout check, elapsed: 30001 ms, remaining: -1 ms
2019-04-21 02:17:54.042 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@e346a92{/0:0:0:0:0:0:0:1:64608<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30001/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a155385[p=HttpParser{s=END,0 of -1},g=HttpGenerator@67d8c0af{s=START}]=>HttpChannelOverHttp@267e1443{r=4,c=false,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=29998} idle timeout expired
2019-04-21 02:17:54.043 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - onFail FillInterest@31d20c1b{null}
java.util.concurrent.TimeoutException: Idle timeout expired: 30001/30000 ms
at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:166) [80:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.IdleTimeout$1.run(IdleTimeout.java:50) [80:org.eclipse.jetty.io:9.4.12.v20180830]
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [?:?]
at java.util.concurrent.FutureTask.run(Unknown Source) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [?:?]
at java.lang.Thread.run(Unknown Source) [?:?]
2019-04-21 02:17:54.043 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - ignored: WriteFlusher@550da60a{IDLE}->null
java.util.concurrent.TimeoutException: Idle timeout expired: 30001/30000 ms
at org.eclipse.jetty.io.IdleTimeout.checkIdleTimeout(IdleTimeout.java:166) [80:org.eclipse.jetty.io:9.4.12.v20180830]
at org.eclipse.jetty.io.IdleTimeout$1.run(IdleTimeout.java:50) [80:org.eclipse.jetty.io:9.4.12.v20180830]
at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [?:?]
at java.util.concurrent.FutureTask.run(Unknown Source) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source) [?:?]
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) [?:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [?:?]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [?:?]
at java.lang.Thread.run(Unknown Source) [?:?]
2019-04-21 02:17:54.043 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - Ignored idle endpoint SocketChannelEndPoint@e346a92{/0:0:0:0:0:0:0:1:64608<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=30002/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a155385[p=HttpParser{s=END,0 of -1},g=HttpGenerator@67d8c0af{s=START}]=>HttpChannelOverHttp@267e1443{r=4,c=false,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=29999}
2019-04-21 02:17:54.638 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Queued change org.eclipse.jetty.io.ManagedSelector$Accept@2e8118b on ManagedSelector@6036a97{STARTED} id=1 keys=3 selected=0 updates=0
2019-04-21 02:17:54.638 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Wakeup on submit ManagedSelector@6036a97{STARTED} id=1 keys=3 selected=0 updates=1
2019-04-21 02:17:54.638 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be woken with none selected
2019-04-21 02:17:54.638 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be woken up from select, 0/0/3 selected
2019-04-21 02:17:54.638 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be processing 0 keys, 1 updates
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 1
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - update org.eclipse.jetty.io.ManagedSelector$Accept@2e8118b
2019-04-21 02:17:54.639 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - queue org.eclipse.jetty.io.ManagedSelector$Accept@2e8118b
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be waiting with 4 keys
2019-04-21 02:17:54.639 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - run org.eclipse.jetty.io.ManagedSelector$Accept@2e8118b
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.io.IdleTimeout ] - SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}-> idle timeout check, elapsed: 0 ms, remaining: 30000 ms
2019-04-21 02:17:54.639 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - new HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0} -> SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}->,null,HttpChannelState@267fdc8e{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false}
2019-04-21 02:17:54.639 [DEBUG] [.eclipse.jetty.server.HttpConnection] - New HTTP Connection HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}->
2019-04-21 02:17:54.640 [DEBUG] [rg.eclipse.jetty.io.AbstractEndPoint] - onOpen SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.640 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - onOpen HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.640 [DEBUG] [.eclipse.jetty.io.AbstractConnection] - fillInterested HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=1/30000}{io=0/0,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.640 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - interested FillInterest@211d5886{AC.ReadCB@401a8e0d{HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/0,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}}}
2019-04-21 02:17:54.640 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - changeInterests p=false 0->1 for SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=0/30000}{io=0/1,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.640 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Queued change org.eclipse.jetty.io.ChannelEndPoint$1@79de6b87 on ManagedSelector@6036a97{STARTED} id=1 keys=4 selected=0 updates=0
2019-04-21 02:17:54.640 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Wakeup on submit ManagedSelector@6036a97{STARTED} id=1 keys=4 selected=0 updates=1
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Created SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=0/1,kio=0,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.641 [DEBUG] [e.jetty.util.thread.QueuedThreadPool] - ran org.eclipse.jetty.io.ManagedSelector$Accept@2e8118b
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be woken with none selected
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be woken up from select, 0/0/4 selected
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be processing 0 keys, 1 updates
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 1
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - update org.eclipse.jetty.io.ChannelEndPoint$1@79de6b87
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - Key interests updated 0 -> 1 on SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=0}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be waiting with 4 keys
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be woken up from select, 1/1/4 selected
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - Selector sun.nio.ch.WindowsSelectorImpl@4a66e7be processing 1 keys, 0 updates
2019-04-21 02:17:54.641 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - selected 1 sun.nio.ch.SelectionKeyImpl@60ade61a SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=1/30000}{io=1/1,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - onSelected 1->0 r=true w=false for SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - task CEP:SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING
2019-04-21 02:17:54.642 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@10880bc{s=2/4,p=0} tryExecute EatWhatYouKill@516c5598/SelectorProducer@12f3e23/PRODUCING/p=false/QueuedThreadPool[qtp630553212]@25957a7c{STARTED,2<=13<=50,i=2,q=0}[ReservedThreadExecutor@10880bc{s=2/4,p=0}][pc=1,pic=0,pec=3,epc=987]@2019-04-21T02:17:54.642+02:00
2019-04-21 02:17:54.642 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@10880bc{s=1/4,p=0}@33ca0533 offer EatWhatYouKill@516c5598/SelectorProducer@12f3e23/PRODUCING/p=false/QueuedThreadPool[qtp630553212]@25957a7c{STARTED,2<=13<=50,i=2,q=0}[ReservedThreadExecutor@10880bc{s=1/4,p=0}][pc=1,pic=0,pec=3,epc=987]@2019-04-21T02:17:54.642+02:00
2019-04-21 02:17:54.642 [DEBUG] [.util.thread.strategy.EatWhatYouKill] - EatWhatYouKill@516c5598/SelectorProducer@12f3e23/IDLE/p=true/QueuedThreadPool[qtp630553212]@25957a7c{STARTED,2<=13<=50,i=2,q=0}[ReservedThreadExecutor@10880bc{s=1/4,p=0}][pc=1,pic=0,pec=3,epc=987]@2019-04-21T02:17:54.642+02:00 m=EXECUTE_PRODUCE_CONSUME t=CEP:SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}:runFillable:BLOCKING/BLOCKING
2019-04-21 02:17:54.642 [DEBUG] [y.util.thread.ReservedThreadExecutor] - ReservedThreadExecutor@10880bc{s=1/4,p=0}@33ca0533 task=EatWhatYouKill@516c5598/SelectorProducer@12f3e23/IDLE/p=true/QueuedThreadPool[qtp630553212]@25957a7c{STARTED,2<=13<=50,i=2,q=0}[ReservedThreadExecutor@10880bc{s=1/4,p=0}][pc=1,pic=0,pec=3,epc=987]@2019-04-21T02:17:54.642+02:00
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.FillInterest ] - fillable FillInterest@211d5886{AC.ReadCB@401a8e0d{HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=FI,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}}}
2019-04-21 02:17:54.642 [DEBUG] [.util.thread.strategy.EatWhatYouKill] - EatWhatYouKill@516c5598/SelectorProducer@12f3e23/IDLE/p=true/QueuedThreadPool[qtp630553212]@25957a7c{STARTED,2<=13<=50,i=2,q=0}[ReservedThreadExecutor@10880bc{s=1/4,p=0}][pc=1,pic=0,pec=3,epc=988]@2019-04-21T02:17:54.642+02:00 tryProduce true
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updateable 0
2019-04-21 02:17:54.642 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0} onFillable enter HttpChannelState@267fdc8e{s=IDLE a=NOT_ASYNC i=true r=IDLE w=false} null
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.ManagedSelector] - updates 0
2019-04-21 02:17:54.642 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - filled 413 SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=2/30000}{io=1/0,kio=1,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0}
2019-04-21 02:17:54.647 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0} filled 413 HeapByteBuffer@7f96c4a5[p=0,l=413,c=8192,r=413]={<<<GET /classicui/ap…9,sl;q=0.8\r\n\r\n>>>=0.8\r\n\r\n;q=0.8\r\n\r…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - sendResponse info=null content=DirectByteBuffer@3001cc[p=0,l=92,c=32768,r=92]={<<<<dest…>>>>or 404 Not Found<…umber",“kind”:"} complete=true committing=true callback=Blocker@131f045b{null}
2019-04-21 02:17:54.647 [DEBUG] [.eclipse.jetty.server.HttpConnection] - HttpConnection@401a8e0d::SocketChannelEndPoint@e7064e7{/0:0:0:0:0:0:0:1:65141<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=-,to=0/30000}{io=0/0,kio=0,kro=1}->HttpConnection@401a8e0d[p=HttpParser{s=START,0 of -1},g=HttpGenerator@4d409db5{s=START}]=>HttpChannelOverHttp@1a5eddf6{r=0,c=false,a=IDLE,uri=null,age=0} parse HeapByteBuffer@7f96c4a5[p=0,l=413,c=8192,r=413]={<<<GET /classicui/ap…9,sl;q=0.8\r\n\r\n>>>=0.8\r\n\r\n;q=0.8\r\n\r…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00} {}
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - COMMIT for /classicui/app on HttpChannelOverHttp@267e1443{r=4,c=true,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=30603}
200 null HTTP/1.1

2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - parseNext s=START HeapByteBuffer@7f96c4a5[p=0,l=413,c=8192,r=413]={<<<GET /classicui/ap…9,sl;q=0.8\r\n\r\n>>>=0.8\r\n\r\n;q=0.8\r\n\r…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00}
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - START --> SPACE1
2019-04-21 02:17:54.647 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@2f0286fd[PROCESSING][i=HTTP/1.1{s=200,h=0,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5f5713a] generate: NEED_HEADER (null,[p=0,l=92,c=32768,r=92],true)@START
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE1 --> URI
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - URI --> SPACE2
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpGenerator] - generateHeaders HTTP/1.1{s=200,h=0,cl=-1} last=true content=DirectByteBuffer@3001cc[p=0,l=92,c=32768,r=92]={<<<<dest…>>>>or 404 Not Found<…umber",“kind”:"}
2019-04-21 02:17:54.647 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - SPACE2 --> REQUEST_VERSION
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - REQUEST_VERSION --> HEADER
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpGenerator] -

2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Host --> VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpGenerator] - CONTENT_LENGTH
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Host --> IN_VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Host --> FIELD
2019-04-21 02:17:54.648 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@2f0286fd[PROCESSING][i=HTTP/1.1{s=200,h=0,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5f5713a] generate: FLUSH ([p=0,l=72,c=8192,r=72],[p=0,l=92,c=32768,r=92],true)@COMPLETING
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Connection: keep-alive --> IN_VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Connection: keep-alive --> FIELD
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - write: WriteFlusher@550da60a{IDLE}->null [HeapByteBuffer@51aafef7[p=0,l=72,c=8192,r=72]={<<<HTTP/1.1 200 OK\r\n…v20180830)\r\n\r\n>>>ce=wa0001 HTTP/1…\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00},DirectByteBuffer@3001cc[p=0,l=92,c=32768,r=92]={<<<<dest…>>>>or 404 Not Found<…umber",“kind”:"}]
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:User-Agent --> VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:User-Agent --> IN_VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@550da60a{WRITING}->null:IDLE–>WRITING
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:User-Agent --> FIELD
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER: --> IN_NAME
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:DNT --> VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:DNT --> IN_VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.io.ChannelEndPoint] - flushed 164 SocketChannelEndPoint@e346a92{/0:0:0:0:0:0:0:1:64608<->/0:0:0:0:0:0:0:1:8080,OPEN,fill=-,flush=W,to=605/30000}{io=0/0,kio=0,kro=1}->HttpConnection@4a155385[p=HttpParser{s=END,0 of -1},g=HttpGenerator@67d8c0af{s=COMPLETING}]=>HttpChannelOverHttp@267e1443{r=4,c=true,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=30604}
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:DNT --> FIELD
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept: / --> IN_VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - Flushed=true written=164 remaining=0 WriteFlusher@550da60a{WRITING}->null
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept: / --> FIELD
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Referer --> VALUE
2019-04-21 02:17:54.648 [DEBUG] [org.eclipse.jetty.io.WriteFlusher ] - update WriteFlusher@550da60a{IDLE}->null:WRITING–>IDLE
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Referer --> IN_VALUE
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Referer --> FIELD
2019-04-21 02:17:54.649 [DEBUG] [.eclipse.jetty.server.HttpConnection] - org.eclipse.jetty.server.HttpConnection$SendCallback@2f0286fd[PROCESSING][i=HTTP/1.1{s=200,h=0,cl=-1},cb=org.eclipse.jetty.server.HttpChannel$CommitCallback@5f5713a] generate: DONE ([p=72,l=72,c=8192,r=0],[p=92,l=92,c=32768,r=0],true)@END
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept-Encoding: gzip, deflate, br --> IN_VALUE
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.server.session ] - FinalSession=null old_session_manager=null this=org.eclipse.jetty.server.session.SessionHandler873416844==dftMaxIdleSec=600
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept-Encoding: gzip, deflate, br --> FIELD
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept-Language --> VALUE
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.server.Server ] - handled=true async=false committed=true on HttpChannelOverHttp@267e1443{r=4,c=true,a=DISPATCHED,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=30605}
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept-Language --> IN_VALUE
2019-04-21 02:17:54.649 [DEBUG] [clipse.jetty.server.HttpChannelState] - unhandle HttpChannelState@60fe2a92{s=DISPATCHED a=NOT_ASYNC i=true r=IDLE w=false}
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER:Accept-Language --> FIELD
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - HttpChannelOverHttp@267e1443{r=4,c=true,a=COMPLETING,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=30605} action COMPLETE
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.http.HttpParser ] - HEADER --> END
2019-04-21 02:17:54.649 [DEBUG] [clipse.jetty.server.HttpChannelState] - onComplete HttpChannelState@60fe2a92{s=COMPLETING a=NOT_ASYNC i=false r=IDLE w=false}
2019-04-21 02:17:54.649 [DEBUG] [org.eclipse.jetty.server.HttpChannel] - REQUEST for //localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001 on HttpChannelOverHttp@1a5eddf6{r=1,c=false,a=IDLE,uri=//localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001,age=0}
GET //localhost:8080/classicui/app?sitemap=_default&w=0001&poll=true&__async=true&__source=wa0001 HTTP/1.1

Thanks

It’s pretty hard to read this, but from a quick look, I don’t see any zwave logs here.

Any suggestion on how to enable z-wave logs?

Thanks

Janko

Yes - this is described in the documentation.