Z-way - Missing configuration

[bogr]

Any clarifications about this one? I see the same thing suddenly:

Code: Select all

[2015-12-06 01:04:43.866] [D] [zway] ---------------------------
[2015-12-06 01:04:43.866] [D] [zway] Initialization done
[2015-12-06 01:04:43.866] [D] [zway] Job 0x07 (Get controller info and supported function classes): success
[2015-12-06 01:04:43.866] [I] [zway] Adding job: Get or set Z-Wave.Me firmware capabilities
[2015-12-06 01:04:43.866] [D] [zway] SETDATA controller.data.controllerState = 0 (0x00000000)
[2015-12-06 01:04:43.866] [I] [zway] Adding job: Set Serial API timeouts
[2015-12-06 01:04:43.867] [I] [zway] Adding job: Set RF power level
[2015-12-06 01:04:43.867] [I] [zway] Adding job: WatchDog Start
[2015-12-06 01:04:43.867] [D] [zway] SETDATA controller.data.frequency = "unknown"
[2015-12-06 01:04:43.867] [E] [zway] Error returned from zway_fc_zme_freq_change(zway, 0xFF, NULL, NULL, NULL): Function class not supported by the hardware (-4)

And then I don't see anything in the logs except for:

Code: Select all

[2015-12-06 19:30:00.082] [I] [core] ---  ZWayVDev_zway_3-0-48-1 performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.083] [I] [zway] Adding job: SensorBinary Get
[2015-12-06 19:30:00.083] [I] [core] ---  ZWayVDev_zway_3-0-49-1 performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.084] [I] [zway] Adding job: SensorMultilevel V5 Get
[2015-12-06 19:30:00.084] [I] [core] ---  ZWayVDev_zway_3-0-49-3 performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.085] [I] [zway] Adding job: SensorMultilevel V5 Get
[2015-12-06 19:30:00.085] [I] [core] ---  ZWayVDev_zway_3-0-49-5 performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.085] [I] [zway] Adding job: SensorMultilevel V5 Get
[2015-12-06 19:30:00.085] [I] [core] ---  ZWayVDev_zway_3-0-49-27 performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.086] [I] [zway] Adding job: SensorMultilevel V5 Get
[2015-12-06 19:30:00.086] [I] [core] ---  ZWayVDev_zway_3-0-113-7-3-A performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.086] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2015-12-06 19:30:00.087] [I] [core] ---  ZWayVDev_zway_3-0-113-7-8-A performCommand processing: {"0":"update"}
[2015-12-06 19:30:00.087] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate

same stuff over and over, and nothing else.

If I press "Call for NIF" i get:

Code: Select all

[2015-12-10 20:26:02.859] [I] [zway] Adding job: Request NIF from a node
[2015-12-10 20:26:02.869] [D] [zway] SENDING: ( 01 04 00 60 01 9A )
[2015-12-10 20:26:02.871] [D] [zway] RECEIVED ACK
[2015-12-10 20:26:02.876] [D] [zway] RECEIVED: ( 01 04 01 60 01 9B )
[2015-12-10 20:26:02.876] [D] [zway] SENT ACK
[2015-12-10 20:26:02.876] [I] [zway] Job 0x60 (Request NIF from a node): Request for node 1 information frame sent
[2015-12-10 20:26:07.268] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 )
[2015-12-10 20:26:07.268] [D] [zway] SENT ACK
[2015-12-10 20:26:07.268] [I] [zway] Node info request failed
[2015-12-10 20:26:07.268] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed
[2015-12-10 20:26:07.268] [D] [zway] SENDING: ( 01 04 00 60 01 9A )
[2015-12-10 20:26:07.269] [D] [zway] RECEIVED ACK
[2015-12-10 20:26:07.275] [D] [zway] RECEIVED: ( 01 04 01 60 01 9B )
[2015-12-10 20:26:07.275] [D] [zway] SENT ACK
[2015-12-10 20:26:07.275] [I] [zway] Job 0x60 (Request NIF from a node): Request for node 1 information frame sent
[2015-12-10 20:26:11.726] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 )
[2015-12-10 20:26:11.726] [D] [zway] SENT ACK
[2015-12-10 20:26:11.726] [I] [zway] Node info request failed
[2015-12-10 20:26:11.726] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed
[2015-12-10 20:26:11.726] [D] [zway] SENDING: ( 01 04 00 60 01 9A )
[2015-12-10 20:26:11.735] [D] [zway] RECEIVED ACK
[2015-12-10 20:26:11.735] [D] [zway] RECEIVED: ( 01 04 01 60 01 9B )
[2015-12-10 20:26:11.735] [D] [zway] SENT ACK
[2015-12-10 20:26:11.735] [I] [zway] Job 0x60 (Request NIF from a node): Request for node 1 information frame sent
[2015-12-10 20:26:16.064] [D] [zway] RECEIVED: ( 01 06 00 49 81 00 00 31 )
[2015-12-10 20:26:16.064] [D] [zway] SENT ACK
[2015-12-10 20:26:16.064] [I] [zway] Node info request failed
[2015-12-10 20:26:16.064] [I] [zway] Job 0x60 (Request NIF from a node): Node info request failed
[2015-12-10 20:26:16.064] [I] [zway] Job 0x60 (Request NIF from a node): Removing job due to too much retransmitions
[2015-12-10 20:26:16.064] [I] [zway] Adding job: Check if node is failed
[2015-12-10 20:26:16.065] [D] [zway] Job 0x60 (Request NIF from a node): fail
[2015-12-10 20:26:16.065] [I] [zway] Removing job: Request NIF from a node
[2015-12-10 20:26:16.065] [D] [zway] SENDING: ( 01 04 00 62 01 98 )
[2015-12-10 20:26:16.071] [D] [zway] RECEIVED ACK
[2015-12-10 20:26:16.071] [D] [zway] RECEIVED: ( 01 04 01 62 01 99 )
[2015-12-10 20:26:16.071] [D] [zway] SENT ACK
[2015-12-10 20:26:16.072] [D] [zway] SETDATA devices.1.data.isFailed = True
[2015-12-10 20:26:16.072] [I] [zway] Job 0x62 (Check if node is failed): Node 1 is failed
[2015-12-10 20:26:16.072] [D] [zway] Job 0x62 (Check if node is failed): success
[2015-12-10 20:26:16.072] [D] [zway] SETDATA devices.1.data.failureCount = 1 (0x00000001)
[2015-12-10 20:26:16.072] [D] [zway] SETDATA devices.1.data.lastSend = 41020490 (0x0271ec4a)
[2015-12-10 20:26:16.072] [I] [zway] Removing job: Check if node is failed

Although I see my device in the Expert Gui, but no values being updated for 6 days.
What did you guys do to get back on track? Just a restart? New interview?

[PoltoS]

First is not a problem.

Second means your devices are sleeping or dead.

Third means same

[bogr]

Ok, but if first is not a problem it's quite misleading that it's logged as an error.

You're right about the device being dead. It drained my battery for the second time in 10 days, so it was suddenly not responding and I returned it to the dealer.

Thanx.

[bogr]

Second means your devices are sleeping or dead.

@PoltoS - just for clarification; do you mean that if I see this in the log:

Code: Select all

[2016-01-06 11:50:00.599] [I] [core] ---  ZWayVDev_zway_2-0-48-1 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.600] [I] [zway] Adding job: SensorBinary Get
[2016-01-06 11:50:00.602] [I] [core] ---  ZWayVDev_zway_2-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.604] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-06 11:50:00.605] [I] [core] ---  ZWayVDev_zway_2-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.606] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-06 11:50:00.608] [I] [core] ---  ZWayVDev_zway_2-0-49-1 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.608] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-06 11:50:00.610] [I] [core] ---  ZWayVDev_zway_2-0-49-3 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.611] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-06 11:50:00.612] [I] [core] ---  ZWayVDev_zway_2-0-49-5 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.612] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-06 11:50:00.613] [I] [core] ---  ZWayVDev_zway_2-0-49-27 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.614] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-06 11:50:00.617] [I] [core] ---  ZWayVDev_zway_4-0-48-1 performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.618] [D] [zway] Job 0x13 (SensorBinary Get): removing duplicate
[2016-01-06 11:50:00.619] [I] [core] ---  ZWayVDev_zway_4-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.620] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-06 11:50:00.621] [I] [core] ---  ZWayVDev_zway_4-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-06 11:50:00.621] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate

the device is sleep or dead? In this case the device is not dead since it reports - I've checked the logs. It is not sleeping either since if I change a config value I can see in the expert UI "Configuration"-tab that the change takes affect immediately, otherwise it says something about "...not stored in device yet...". The "Wireless Activity" in Interview tab also says that "Device is currently awake". Maybe has to do with the fact that I switched to running in secure mode?

[PoltoS]

Need to see more logs to understand device types.

[bogr]

ok, here you go:

Code: Select all

[2016-01-07 17:40:00.988] [I] [core] ---  ZWayVDev_zway_2-0-48-1 performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.989] [D] [zway] Job 0x13 (SensorBinary Get): removing duplicate
[2016-01-07 17:40:00.990] [I] [core] ---  ZWayVDev_zway_2-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.991] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:40:00.992] [I] [core] ---  ZWayVDev_zway_2-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.992] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:40:00.993] [I] [core] ---  ZWayVDev_zway_2-0-49-1 performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.994] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:00.995] [I] [core] ---  ZWayVDev_zway_2-0-49-3 performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.995] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:00.996] [I] [core] ---  ZWayVDev_zway_2-0-49-5 performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.996] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:00.996] [I] [core] ---  ZWayVDev_zway_2-0-49-27 performCommand processing: {"0":"update"}
[2016-01-07 17:40:00.997] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:00.999] [I] [core] ---  ZWayVDev_zway_4-0-48-1 performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.000] [D] [zway] Job 0x13 (SensorBinary Get): removing duplicate
[2016-01-07 17:40:01.001] [I] [core] ---  ZWayVDev_zway_4-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.001] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:40:01.002] [I] [core] ---  ZWayVDev_zway_4-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.003] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:40:01.004] [I] [core] ---  ZWayVDev_zway_4-0-49-1 performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.005] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:01.005] [I] [core] ---  ZWayVDev_zway_4-0-49-3 performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.006] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:01.007] [I] [core] ---  ZWayVDev_zway_4-0-49-5 performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.007] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:40:01.008] [I] [core] ---  ZWayVDev_zway_4-0-49-27 performCommand processing: {"0":"update"}
[2016-01-07 17:40:01.008] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:42:26.416] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D )
[2016-01-07 17:42:26.416] [D] [zway] SENT ACK
[2016-01-07 17:42:26.416] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:26.416] [I] [zway] Node 4:0 CC Security: sending Nonce Report
[2016-01-07 17:42:26.417] [I] [zway] Adding job: Nonce Report
[2016-01-07 17:42:26.417] [D] [zway] SENDING (cb 0x16): ( 01 11 00 13 04 0A 98 80 02 B5 96 A7 C6 F2 5E 2E 05 16 3A )
[2016-01-07 17:42:26.426] [D] [zway] RECEIVED ACK
[2016-01-07 17:42:26.426] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-07 17:42:26.426] [D] [zway] SENT ACK
[2016-01-07 17:42:26.426] [D] [zway] Delivered to Z-Wave stack
[2016-01-07 17:42:26.446] [D] [zway] RECEIVED: ( 01 07 00 13 16 00 00 02 FF )
[2016-01-07 17:42:26.446] [D] [zway] SENT ACK
[2016-01-07 17:42:26.447] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-07 17:42:26.447] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True
[2016-01-07 17:42:26.447] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-07 17:42:26.447] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 20 (0x00000014)
[2016-01-07 17:42:26.447] [D] [zway] SETDATA devices.4.data.lastPacketInfo = **********
[2016-01-07 17:42:26.447] [D] [zway] SendData Response with callback 0x16 received: received by recipient
[2016-01-07 17:42:26.447] [D] [zway] SETDATA devices.4.data.lastSend = 15732857 (0x00f01079)
[2016-01-07 17:42:26.447] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-07 17:42:26.447] [I] [zway] Removing job: Nonce Report
[2016-01-07 17:42:26.466] [D] [zway] RECEIVED: ( 01 20 00 04 00 04 1A 98 81 1C D4 DC 77 82 54 E9 BB BB 70 FA 1E F8 F4 76 02 53 F6 1D C7 F5 E2 B3 EC 5B )
[2016-01-07 17:42:26.466] [D] [zway] SENT ACK
[2016-01-07 17:42:26.466] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:26.466] [I] [zway] Node 4:0 CC Security: Received a secure message
[2016-01-07 17:42:26.467] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-07 17:42:26.467] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 31 05 01 22 00 e7 ]
[2016-01-07 17:42:26.467] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.deviceScale = 0 (0x00000000)
[2016-01-07 17:42:26.467] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scale = 0 (0x00000000)
[2016-01-07 17:42:26.468] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.val = 23.100000
[2016-01-07 17:42:26.470] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1.scaleString = "°C"
[2016-01-07 17:42:26.470] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.1 = Empty
[2016-01-07 17:42:26.483] [I] [core] Notification: device-info (device-temperature): {"dev":"Sensor Temperature 4-0-49-1 Aeon Labs","l":"23.1 °C"}
[2016-01-07 17:42:27.128] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D )
[2016-01-07 17:42:27.128] [D] [zway] SENT ACK
[2016-01-07 17:42:27.128] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:27.129] [I] [zway] Node 4:0 CC Security: sending Nonce Report
[2016-01-07 17:42:27.129] [I] [zway] Adding job: Nonce Report
[2016-01-07 17:42:27.129] [D] [zway] SENDING (cb 0x17): ( 01 11 00 13 04 0A 98 80 85 80 3E FD 90 DC 06 F3 05 17 86 )
[2016-01-07 17:42:27.138] [D] [zway] RECEIVED ACK
[2016-01-07 17:42:27.138] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-07 17:42:27.139] [D] [zway] SENT ACK
[2016-01-07 17:42:27.139] [D] [zway] Delivered to Z-Wave stack
[2016-01-07 17:42:27.158] [D] [zway] RECEIVED: ( 01 07 00 13 17 00 00 02 FE )
[2016-01-07 17:42:27.158] [D] [zway] SENT ACK
[2016-01-07 17:42:27.158] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-07 17:42:27.158] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True
[2016-01-07 17:42:27.158] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-07 17:42:27.159] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-07 17:42:27.159] [D] [zway] SETDATA devices.4.data.lastPacketInfo = **********
[2016-01-07 17:42:27.159] [D] [zway] SendData Response with callback 0x17 received: received by recipient
[2016-01-07 17:42:27.159] [D] [zway] SETDATA devices.4.data.lastSend = 15732927 (0x00f010bf)
[2016-01-07 17:42:27.159] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-07 17:42:27.159] [I] [zway] Removing job: Nonce Report
[2016-01-07 17:42:27.178] [D] [zway] RECEIVED: ( 01 1F 00 04 00 04 19 98 81 F2 26 F2 A5 D1 CD F6 A0 1D C3 07 3F 1D 1F 85 14 41 2B D2 4F B3 07 E3 FC )
[2016-01-07 17:42:27.178] [D] [zway] SENT ACK
[2016-01-07 17:42:27.178] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:27.178] [I] [zway] Node 4:0 CC Security: Received a secure message
[2016-01-07 17:42:27.178] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-07 17:42:27.178] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 31 05 05 01 11 ]
[2016-01-07 17:42:27.179] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.5.deviceScale = 0 (0x00000000)
[2016-01-07 17:42:27.179] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.5.scale = 0 (0x00000000)
[2016-01-07 17:42:27.179] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.5.val = 17.000000
[2016-01-07 17:42:27.181] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.5.scaleString = "%"
[2016-01-07 17:42:27.181] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.5 = Empty
[2016-01-07 17:42:28.207] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D )
[2016-01-07 17:42:28.207] [D] [zway] SENT ACK
[2016-01-07 17:42:28.207] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.208] [I] [zway] Node 4:0 CC Security: sending Nonce Report
[2016-01-07 17:42:28.208] [I] [zway] Adding job: Nonce Report
[2016-01-07 17:42:28.208] [D] [zway] SENDING (cb 0x18): ( 01 11 00 13 04 0A 98 80 E2 68 C6 14 D8 29 94 21 05 18 EA )
[2016-01-07 17:42:28.217] [D] [zway] RECEIVED ACK
[2016-01-07 17:42:28.218] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-07 17:42:28.218] [D] [zway] SENT ACK
[2016-01-07 17:42:28.218] [D] [zway] Delivered to Z-Wave stack
[2016-01-07 17:42:28.237] [D] [zway] RECEIVED: ( 01 07 00 13 18 00 00 02 F1 )
[2016-01-07 17:42:28.237] [D] [zway] SENT ACK
[2016-01-07 17:42:28.237] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-07 17:42:28.237] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True
[2016-01-07 17:42:28.237] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-07 17:42:28.237] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-07 17:42:28.237] [D] [zway] SETDATA devices.4.data.lastPacketInfo = **********
[2016-01-07 17:42:28.238] [D] [zway] SendData Response with callback 0x18 received: received by recipient
[2016-01-07 17:42:28.238] [D] [zway] SETDATA devices.4.data.lastSend = 15733033 (0x00f01129)
[2016-01-07 17:42:28.238] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-07 17:42:28.238] [I] [zway] Removing job: Nonce Report
[2016-01-07 17:42:28.248] [D] [zway] RECEIVED: ( 01 1D 00 04 00 04 17 98 81 E0 8D A5 DF 84 1A A8 B8 E8 50 49 28 E2 BC 13 53 D3 BC C5 1F 64 63 )
[2016-01-07 17:42:28.248] [D] [zway] SENT ACK
[2016-01-07 17:42:28.248] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.248] [I] [zway] Node 4:0 CC Security: Received a secure message
[2016-01-07 17:42:28.248] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-07 17:42:28.248] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 80 03 64 ]
[2016-01-07 17:42:28.249] [D] [zway] SETDATA devices.4.instances.0.commandClasses.128.data.history.100 = 1452184948 (0x568e9574)
[2016-01-07 17:42:28.249] [D] [zway] SETDATA devices.4.instances.0.commandClasses.128.data.last = 100 (0x00000064)
[2016-01-07 17:42:28.308] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D )
[2016-01-07 17:42:28.308] [D] [zway] SENT ACK
[2016-01-07 17:42:28.308] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.309] [I] [zway] Node 4:0 CC Security: sending Nonce Report
[2016-01-07 17:42:28.309] [I] [zway] Adding job: Nonce Report
[2016-01-07 17:42:28.309] [D] [zway] SENDING (cb 0x19): ( 01 11 00 13 04 0A 98 80 A0 38 4F 33 1C 8C F9 4F 05 19 35 )
[2016-01-07 17:42:28.319] [D] [zway] RECEIVED ACK
[2016-01-07 17:42:28.319] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-07 17:42:28.319] [D] [zway] SENT ACK
[2016-01-07 17:42:28.319] [D] [zway] Delivered to Z-Wave stack
[2016-01-07 17:42:28.338] [D] [zway] RECEIVED: ( 01 07 00 13 19 00 00 02 F0 )
[2016-01-07 17:42:28.338] [D] [zway] SENT ACK
[2016-01-07 17:42:28.338] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-07 17:42:28.338] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True
[2016-01-07 17:42:28.338] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-07 17:42:28.338] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 19 (0x00000013)
[2016-01-07 17:42:28.339] [D] [zway] SETDATA devices.4.data.lastPacketInfo = **********
[2016-01-07 17:42:28.339] [D] [zway] SendData Response with callback 0x19 received: received by recipient
[2016-01-07 17:42:28.339] [D] [zway] SETDATA devices.4.data.lastSend = 15733043 (0x00f01133)
[2016-01-07 17:42:28.339] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-07 17:42:28.339] [I] [zway] Removing job: Nonce Report
[2016-01-07 17:42:28.358] [D] [zway] RECEIVED: ( 01 20 00 04 00 04 1A 98 81 35 D9 C7 1A 23 B2 D0 4A AB 0B 4F 40 85 3D C7 A0 18 A1 47 9C BB BC 37 B1 75 )
[2016-01-07 17:42:28.358] [D] [zway] SENT ACK
[2016-01-07 17:42:28.358] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.358] [I] [zway] Node 4:0 CC Security: Received a secure message
[2016-01-07 17:42:28.358] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-07 17:42:28.358] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 31 05 03 0a 00 0c ]
[2016-01-07 17:42:28.358] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.3.deviceScale = 1 (0x00000001)
[2016-01-07 17:42:28.358] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.3.scale = 1 (0x00000001)
[2016-01-07 17:42:28.358] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.3.val = 12.000000
[2016-01-07 17:42:28.360] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.3.scaleString = "Lux"
[2016-01-07 17:42:28.360] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.3 = Empty
[2016-01-07 17:42:28.367] [I] [core] Notification: device-info (device-luminiscence): {"dev":"Sensor Luminiscence 4-0-49-3 Aeon Labs","l":"12 null"}
[2016-01-07 17:42:28.449] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 98 40 2D )
[2016-01-07 17:42:28.449] [D] [zway] SENT ACK
[2016-01-07 17:42:28.449] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.450] [I] [zway] Node 4:0 CC Security: sending Nonce Report
[2016-01-07 17:42:28.450] [I] [zway] Adding job: Nonce Report
[2016-01-07 17:42:28.450] [D] [zway] SENDING (cb 0x1a): ( 01 11 00 13 04 0A 98 80 F7 5A E2 A1 47 63 79 53 05 1A 14 )
[2016-01-07 17:42:28.459] [D] [zway] RECEIVED ACK
[2016-01-07 17:42:28.459] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-07 17:42:28.459] [D] [zway] SENT ACK
[2016-01-07 17:42:28.459] [D] [zway] Delivered to Z-Wave stack
[2016-01-07 17:42:28.479] [D] [zway] RECEIVED: ( 01 07 00 13 1A 00 00 02 F3 )
[2016-01-07 17:42:28.479] [D] [zway] SENT ACK
[2016-01-07 17:42:28.479] [I] [zway] Job 0x13 (Nonce Report): Delivered
[2016-01-07 17:42:28.479] [D] [zway] SETDATA devices.4.data.lastPacketInfo.delivered = True
[2016-01-07 17:42:28.479] [D] [zway] SETDATA devices.4.data.lastPacketInfo.packetLength = 13 (0x0000000d)
[2016-01-07 17:42:28.480] [D] [zway] SETDATA devices.4.data.lastPacketInfo.deliveryTime = 20 (0x00000014)
[2016-01-07 17:42:28.480] [D] [zway] SETDATA devices.4.data.lastPacketInfo = **********
[2016-01-07 17:42:28.480] [D] [zway] SendData Response with callback 0x1a received: received by recipient
[2016-01-07 17:42:28.480] [D] [zway] SETDATA devices.4.data.lastSend = 15733057 (0x00f01141)
[2016-01-07 17:42:28.480] [D] [zway] Job 0x13 (Nonce Report): success
[2016-01-07 17:42:28.480] [I] [zway] Removing job: Nonce Report
[2016-01-07 17:42:28.490] [D] [zway] RECEIVED: ( 01 1F 00 04 00 04 19 98 81 1D 93 12 3F A2 74 8B B0 7E 79 CD 53 A1 41 F7 CD FE 28 68 5E DD FA 93 B9 )
[2016-01-07 17:42:28.490] [D] [zway] SENT ACK
[2016-01-07 17:42:28.490] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.490] [I] [zway] Node 4:0 CC Security: Received a secure message
[2016-01-07 17:42:28.490] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.firstPart = **********
[2016-01-07 17:42:28.490] [I] [zway] Node 4:0 CC Security: passing decrypted packet to application level: [ 31 05 1b 01 00 ]
[2016-01-07 17:42:28.490] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.27.deviceScale = 0 (0x00000000)
[2016-01-07 17:42:28.491] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.27.scale = 0 (0x00000000)
[2016-01-07 17:42:28.491] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.27.val = 0.000000
[2016-01-07 17:42:28.492] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.27.scaleString = "UV index"
[2016-01-07 17:42:28.492] [D] [zway] SETDATA devices.4.instances.0.commandClasses.49.data.27 = Empty
[2016-01-07 17:42:28.570] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 84 07 76 )
[2016-01-07 17:42:28.571] [D] [zway] SENT ACK
[2016-01-07 17:42:28.571] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:42:28.571] [W] [zway] Got unsecure packet from device 4, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-07 17:42:46.954] [D] [zway] Job 0x13: deleted from queue
[2016-01-07 17:42:47.616] [D] [zway] Job 0x13: deleted from queue
[2016-01-07 17:42:48.635] [D] [zway] Job 0x13: deleted from queue
[2016-01-07 17:42:48.696] [D] [zway] Job 0x13: deleted from queue
[2016-01-07 17:42:48.788] [D] [zway] Job 0x13: deleted from queue
[2016-01-07 17:43:34.051] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-07 17:43:34.051] [D] [zway] SENT ACK
[2016-01-07 17:43:34.052] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:43:34.052] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-07 17:47:26.770] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 84 07 76 )
[2016-01-07 17:47:26.770] [D] [zway] SENT ACK
[2016-01-07 17:47:26.771] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:47:26.771] [W] [zway] Got unsecure packet from device 4, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-07 17:48:34.040] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-07 17:48:34.041] [D] [zway] SENT ACK
[2016-01-07 17:48:34.041] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:48:34.041] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-07 17:50:00.127] [I] [core] ---  ZWayVDev_zway_2-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.127] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:50:00.128] [I] [core] ---  ZWayVDev_zway_2-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.128] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:50:00.129] [I] [core] ---  ZWayVDev_zway_2-0-49-1 performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.129] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:50:00.130] [I] [core] ---  ZWayVDev_zway_2-0-49-3 performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.130] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:50:00.131] [I] [core] ---  ZWayVDev_zway_2-0-49-5 performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.131] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:50:00.132] [I] [core] ---  ZWayVDev_zway_2-0-49-27 performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.132] [D] [zway] Job 0x13 (SensorMultilevel V5 Get): removing duplicate
[2016-01-07 17:50:00.133] [I] [core] ---  ZWayVDev_zway_4-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.134] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:50:00.134] [I] [core] ---  ZWayVDev_zway_4-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-07 17:50:00.134] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-07 17:52:26.767] [D] [zway] RECEIVED: ( 01 08 00 04 00 04 02 84 07 76 )
[2016-01-07 17:52:26.768] [D] [zway] SENT ACK
[2016-01-07 17:52:26.768] [D] [zway] SETDATA devices.4.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:52:26.768] [W] [zway] Got unsecure packet from device 4, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.
[2016-01-07 17:53:34.042] [D] [zway] RECEIVED: ( 01 08 00 04 00 02 02 84 07 70 )
[2016-01-07 17:53:34.042] [D] [zway] SENT ACK
[2016-01-07 17:53:34.042] [D] [zway] SETDATA devices.2.data.lastReceived = 0 (0x00000000)
[2016-01-07 17:53:34.042] [W] [zway] Got unsecure packet from device 2, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.

That's the absolute latest traces. Don't know if it is enough, but let me know otherwise.

Thanx!

[PoltoS]

The device wakes up and reports all the sensor values itself. But your polling does not work due to this:

Code: Select all

[2016-01-07 17:42:28.571] [W] [zway] Got unsecure packet from device 4, but Command Class 0x84 Wakeup is marked as secure. Ignoring packet.

Looks like Aeon or Philio device. This is a know bug. Can be solved by doing:

Code: Select all

http://ip:8083/JS/Run/zway.devices[4].Wakup.data.security=false

[bogr]

Ok thanx, I've set the security to false, and now it looks slightly different in the log:

Code: Select all

[2016-01-10 14:10:00.116] [I] [core] ---  ZWayVDev_zway_2-0-49-3 performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.117] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-10 14:10:00.118] [I] [core] ---  ZWayVDev_zway_2-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.119] [I] [zway] Node 2:0 CC Security: sending Nonce Get
[2016-01-10 14:10:00.119] [D] [zway] SETDATA devices.2.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2016-01-10 14:10:00.119] [I] [zway] Adding job: Nonce Get
[2016-01-10 14:10:00.119] [D] [zway] SETDATA devices.2.data.lastNonceGet = 1602752 (0x001874c0)
[2016-01-10 14:10:00.120] [I] [zway] Adding job: Alarm Get (v3)
[2016-01-10 14:10:00.120] [I] [core] ---  ZWayVDev_zway_2-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.121] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-10 14:10:00.122] [I] [core] ---  ZWayVDev_zway_4-0-49-1 performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.122] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-10 14:10:00.123] [I] [core] ---  ZWayVDev_zway_4-0-49-5 performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.123] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-10 14:10:00.123] [I] [core] ---  ZWayVDev_zway_4-0-49-27 performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.124] [I] [zway] Adding job: SensorMultilevel V5 Get
[2016-01-10 14:10:00.124] [I] [core] ---  ZWayVDev_zway_4-0-113-7-3-A performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.124] [I] [zway] Adding job: Alarm Get (v3)
[2016-01-10 14:10:00.125] [I] [core] ---  ZWayVDev_zway_4-0-113-7-8-A performCommand processing: {"0":"update"}
[2016-01-10 14:10:00.125] [D] [zway] Job 0x13 (Alarm Get (v3)): removing duplicate
[2016-01-10 14:10:00.130] [I] [zway] Node 4:0 CC Security: sending Nonce Get
[2016-01-10 14:10:00.130] [D] [zway] SETDATA devices.4.instances.0.commandClasses.152.data.rNonceAckWait = **********
[2016-01-10 14:10:00.130] [I] [zway] Adding job: Nonce Get
[2016-01-10 14:10:00.130] [D] [zway] SETDATA devices.4.data.lastNonceGet = 1602753 (0x001874c1)
[2016-01-10 14:10:00.130] [D] [zway] SENDING (cb 0xeb): ( 01 09 00 13 02 02 98 40 05 EB D3 )
[2016-01-10 14:10:00.132] [D] [zway] RECEIVED ACK
[2016-01-10 14:10:00.136] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2016-01-10 14:10:00.136] [D] [zway] SENT ACK
[2016-01-10 14:10:00.136] [D] [zway] Delivered to Z-Wave stack
[2016-01-10 14:10:00.374] [D] [zway] RECEIVED: ( 01 07 00 13 EB 01 00 18 19 )
[2016-01-10 14:10:00.375] [D] [zway] SENT ACK

Is this better? What exactly was indicating that the device was sleeping or dead in the log? The many occurances of "removing duplicate"?