Bernd,
I’m using the old (2012) hub. I always have debugging on (too much tinkering I guess). Have fooled around with Houselinc, but don’t really have any documentation to work out what the options here are.
Looking at the log, I see events when the sensor closes, and events when it opens, but much of it is ALL_LINK_CLEANUP and so on. For instance, here is a snip of the log, filtered for my door sensor. I close the sensor (detected), then wait a second or so, then open it. There is activity when I open the sensor, but OPEN is not published. Here it is:
2015-11-30 13:35:06 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x11|command2:0x01|
2015-11-30 13:35:06 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x11|command2:0x01|
2015-11-30 13:35:06 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x11|command2:0x01|
2015-11-30 13:35:06 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:06
2015-11-30 13:35:07 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:72]- 32.8C.35:HiddenDoorSensorData->NoOpMsgHandler cmd1:0x13 group 1/1:IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:72]- 32.8C.35:WirelessMotionSensorContact->ClosedSleepingContactHandler cmd1:0x13 group 1/1:IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:WirelessMotionSensorContact publishing: CLOSED
2015-11-30 13:35:07 DEBUG o.o.b.i.i.device.InsteonDevice[:370]- still waiting for query reply from 32.8C.35 for another 805 usec
2015-11-30 13:35:07 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:07
2015-11-30 13:35:07 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:00.00.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:07
2015-11-30 13:35:07 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x41=ALL_LINK_CLEANUP:1:0|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x41=ALL_LINK_CLEANUP:1:0|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x41=ALL_LINK_CLEANUP:1:0|command1:0x13|command2:0x01|
2015-11-30 13:35:07 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:07
2015-11-30 13:35:08 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x46=ALL_LINK_CLEANUP:2:1|command1:0x13|command2:0x01|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x46=ALL_LINK_CLEANUP:2:1|command1:0x13|command2:0x01|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x46=ALL_LINK_CLEANUP:2:1|command1:0x13|command2:0x01|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:08
2015-11-30 13:35:08 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:08
2015-11-30 13:35:08 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:13.01.01|messageFlags:0xCB=ALL_LINK_BROADCAST:3:2|command1:0x06|command2:0x00|
2015-11-30 13:35:08 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:08
2015-11-30 13:35:09 DEBUG o.o.b.i.i.device.InsteonDevice[:374]- gave up waiting for query reply from device 32.8C.35
2015-11-30 13:35:09 DEBUG o.o.b.i.i.device.InsteonDevice[:379]- qe taken off direct: WirelessMotionSensorContact(1:1:5) OUT:Cmd:0x62|toAddress:32.8C.35|messageFlags:0x1F=DIRECT:3:3|command1:0x2E|command2:0x00|userData1:0x00|userData2:0x00|userData3:0x00|userData4:0x00|userData5:0x00|userData6:0x00|userData7:0x00|userData8:0x00|userData9:0x00|userData10:0x00|userData11:0x00|userData12:0x00|userData13:0x00|userData14:0xD2|
2015-11-30 13:35:11 DEBUG o.o.b.i.i.d.RequestQueueManager[:132]- device queue for 32.8C.35 is empty!
2015-11-30 13:35:17 DEBUG o.o.b.i.internal.driver.Port[:382]- writing (500): OUT:Cmd:0x62|toAddress:32.8C.35|messageFlags:0x1F=DIRECT:3:3|command1:0x2E|command2:0x00|userData1:0x00|userData2:0x00|userData3:0x00|userData4:0x00|userData5:0x00|userData6:0x00|userData7:0x00|userData8:0x00|userData9:0x00|userData10:0x00|userData11:0x00|userData12:0x00|userData13:0x00|userData14:0xD2|
2015-11-30 13:35:17 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x47=ALL_LINK_CLEANUP:3:1|command1:0x11|command2:0x01|
2015-11-30 13:35:17 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x47=ALL_LINK_CLEANUP:3:1|command1:0x11|command2:0x01|
2015-11-30 13:35:17 DEBUG o.o.b.i.i.d.MessageDispatcher[:68]- all link message: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x47=ALL_LINK_CLEANUP:3:1|command1:0x11|command2:0x01|
2015-11-30 13:35:17 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:17
2015-11-30 13:35:18 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x2B=ACK_OF_DIRECT:3:2|command1:0x2E|command2:0x00|
2015-11-30 13:35:18 DEBUG o.o.b.i.i.d.MessageDispatcher[:134]- 32.8C.35:WirelessMotionSensorContact DIRECT_ACK: q:QUERY_PENDING cmd: 80
2015-11-30 13:35:18 DEBUG o.o.b.i.i.d.MessageDispatcher[:139]- changing key to 0x19 for msg IN:Cmd:0x50|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x2B=ACK_OF_DIRECT:3:2|command1:0x2E|command2:0x00|
2015-11-30 13:35:18 DEBUG o.o.b.i.i.d.MessageDispatcher[:159]- defdisp: 32.8C.35:WirelessMotionSensorContact set status to: QUERY_ANSWERED
2015-11-30 13:35:18 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:18
2015-11-30 13:35:19 DEBUG o.o.b.i.InsteonPLMActiveBinding[:522]- got msg: IN:Cmd:0x51|fromAddress:32.8C.35|toAddress:2A.E7.67|messageFlags:0x17=DIRECT:3:1|command1:0x2E|command2:0x00|userData1:0x01|userData2:0x01|userData3:0x3D|userData4:0x57|userData5:0x00|userData6:0x40|userData7:0x40|userData8:0x00|userData9:0x00|userData10:0x00|userData11:0x00|userData12:0x00|userData13:0x00|userData14:0xD2|
2015-11-30 13:35:19 DEBUG o.o.b.i.i.d.MessageHandler[:607]- HiddenDoorSensorDataReplyHandler: 32.8C.35 got light level: 64, battery level: 87
2015-11-30 13:35:19 DEBUG o.o.b.i.i.device.DeviceFeature[:249]- 32.8C.35:HiddenDoorSensorData publishing: 64
2015-11-30 13:35:19 DEBUG o.o.b.i.i.device.DeviceFeature[:249]- 32.8C.35:HiddenDoorSensorData publishing: 87
2015-11-30 13:35:19 DEBUG o.o.b.i.i.d.MessageDispatcher[:151]- 32.8C.35:WirelessMotionSensorContact->NoOpMsgHandler DIRECT
2015-11-30 13:35:19 DEBUG o.o.b.i.i.device.DeviceFeature[:263]- 32.8C.35:GenericLastTime publishing: 2015-11-30T13:35:19
2015-11-30 13:35:20 DEBUG o.o.b.i.internal.driver.Port[:382]- writing (500): OUT:Cmd:0x62|toAddress:32.8C.35|messageFlags:0x1F=DIRECT:3:3|command1:0x2E|command2:0x00|userData1:0x00|userData2:0x00|userData3:0x00|userData4:0x00|userData5:0x00|userData6:0x00|userData7:0x00|userData8:0x00|userData9:0x00|userData10:0x00|userData11:0x00|userData12:0x00|userData13:0x00|userData14:0xD2|
I close the sensor at 13:35:06, at 13:35:07 CLOSE is published. I then open the sensor at (or about) 13:35:17 (10 seconds later - so some time), there is a flurry of activity, last heard from is updated etc. but no OPEN published. This is repeatable. Not sure what is going on. I will investigate further with different Houselinc settings.
Regards,