2019-06-21 22:09:17.452 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Stop zwave:serial_zstick:b7680128 2019-06-21 22:09:17.459 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller stopping inclusion at IncludeDone 2019-06-21 22:09:17.461 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller end exclusion 2019-06-21 22:09:17.465 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWaveController include done 2019-06-21 22:09:17.469 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - ZWave discovery: Start zwave:serial_zstick:b7680128 2019-06-21 22:09:17.471 [DEBUG] [ve.internal.protocol.ZWaveController] - ZWave controller start inclusion - mode 2 2019-06-21 22:09:17.474 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added. 2019-06-21 22:09:17.477 [DEBUG] [al.protocol.ZWaveInclusionController] - ZWave controller start inclusion 2019-06-21 22:09:17.480 [DEBUG] [ol.serialmessage.AddNodeMessageClass] - Setting controller into INCLUSION mode, highPower:true networkWide:true. 2019-06-21 22:09:17.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: Added 111 to queue - size 1 2019-06-21 22:09:17.485 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2019-06-21 22:09:17.489 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 05 00 4A C1 11 60 2019-06-21 22:09:17.492 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 05 00 4A C1 11 60 2019-06-21 22:09:17.495 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2019-06-21 22:09:17.497 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 111: [WAIT_REQUEST] priority=Controller, requiresResponse=true, callback: 17 2019-06-21 22:09:19.502 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery completed 2019-06-21 22:09:19.582 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 2: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.588 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery completed 2019-06-21 22:09:19.609 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 3: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.616 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery completed 2019-06-21 22:09:19.625 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 4: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.630 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery completed 2019-06-21 22:09:19.635 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.640 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery completed 2019-06-21 22:09:19.645 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 7: Device discovery resolved to thingType zwave:philio_pan081a_00_000 2019-06-21 22:09:19.661 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery completed 2019-06-21 22:09:19.666 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 8: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.675 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery completed 2019-06-21 22:09:19.680 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 9: Device discovery could not resolve to a thingType! Manufacturer data not known. 2019-06-21 22:09:19.684 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'zwave:device:b7680128:node9' to inbox. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:19.685 [home.event.InboxAddedEvent] - Discovery Result with UID 'zwave:device:b7680128:node9' has been added. ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:19.686 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery completed 2019-06-21 22:09:19.691 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 10: Device discovery resolved to thingType zwave:philio_pan04_00_000 2019-06-21 22:09:22.500 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 255: TID 111: Timeout at state WAIT_REQUEST. 3 retries remaining. 2019-06-21 22:09:22.508 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 111: Transaction is current transaction, so clearing!!!!! 2019-06-21 22:09:22.510 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 111: Transaction CANCELLED 2019-06-21 22:09:22.512 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 255: notifyTransactionResponse TID:111 CANCELLED 2019-06-21 22:09:22.521 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:39.439 [me.event.ThingUpdatedEvent] - Thing 'yamahareceiver:zone:9ab0c000_f668_11de_9976_ac44f241f775:Zone_2' has been updated. 2019-06-21 22:09:39.446 [me.event.ThingUpdatedEvent] - Thing 'yamahareceiver:zone:9ab0c000_f668_11de_9976_ac44f241f775:Main_Zone' has been updated. 2019-06-21 22:09:54.689 [me.event.InboxRemovedEvent] - Discovery Result with UID 'zwave:device:b7680128:node9' has been removed. ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.698 [DEBUG] [org.openhab.binding.zwave ] - ServiceEvent REGISTERED - {org.eclipse.smarthome.config.core.status.ConfigStatusProvider}={service.id=481, service.bundleid=250, service.scope=singleton} - org.openhab.binding.zwave ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:54.722 [hingStatusInfoChangedEvent] - 'zwave:device:b7680128:node9' changed from UNINITIALIZED to INITIALIZING ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.759 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - Initializing ZWave thing handler zwave:device:b7680128:node9. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:54.806 [hingStatusInfoChangedEvent] - 'zwave:device:b7680128:node9' changed from INITIALIZING to OFFLINE (BRIDGE_OFFLINE): Controller is offline ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.824 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: MANUFACTURER not set 2019-06-21 22:09:54.826 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller status changed to ONLINE. 2019-06-21 22:09:54.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Controller is ONLINE. Starting device initialisation. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:54.833 [hingStatusInfoChangedEvent] - 'zwave:device:b7680128:node9' changed from OFFLINE (BRIDGE_OFFLINE): Controller is offline to ONLINE ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.859 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:54.860 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:b7680128:node9' has been updated. ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.865 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Updating node properties. MAN=2147483647 2019-06-21 22:09:54.868 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Properties synchronised ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:54.869 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:b7680128:node9' has been updated. 2019-06-21 22:09:54.876 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:b7680128:node9' has been updated. ==> /var/log/openhab2/openhab.log <== 2019-06-21 22:09:54.875 [DEBUG] [ve.internal.protocol.ZWaveController] - Event listener added. 2019-06-21 22:09:54.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Initialising Thing Node... 2019-06-21 22:09:54.881 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Polling intialised at 1800 seconds - start in 257400 milliseconds. 2019-06-21 22:09:54.883 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 9: Device initialisation complete. ==> /var/log/openhab2/events.log <== 2019-06-21 22:09:56.155 [vent.ItemStateChangedEvent] - XiaomiAqaraMotionSensor2LastMotion changed from 2019-06-21T22:08:40.755+0300 to 2019-06-21T22:09:56.132+0300