[Z-Wave] Home Energy Monitor readings will not update

rooflz · December 4, 2015, 4:30am

I have openhab running on a raspberry pi 2 model b. I then have an Aeon Labs Z-Stick S2 Z-Wave USB Controller. I have a number of zwave devices which are working as expected, however I’m having trouble getting data from my Aeon Labs Home Energy Meter (first generation). It will retrieve an initial value from the meter, however it will never update after that. The only way to get the value to update is to modify my conf.items file. It then will update a single time, but never again. A word of warning…I’m extremely new to all of this and know very little, so any help/direction/links to additional reading are very much appreciated.

A few things to note about my HEM:

It is plugged in and not running off batteries
It is NOT a neighbor of my Z-Stick S2
It often shows up as yellow in habmin
I set this up to persist this data every minute using rrd4j
I’m not sure how to use association groups with it to get data to auto report.

My conf.items entry for the Aeon Labs Home Energy Meter:

Number Energy_HomeW “Home Energy Usage [%.2f W]” { zwave=“5:command=METER,meter_scale=E_W” }

Below is the information from the zwave.log pertaining to NODE 5, which is the Home Energy Meter. This file got very large and would likely be too much to attach to this thread. If I am filtering out any vital information please let me know:

22:33:41.738 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 32 01 10 25 7C 9A
Line 11323: 2015-12-03 22:33:41.750 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11337: 2015-12-03 22:33:45.518 [DEBUG] [ApplicationCommandMessageClass:38 ]- NODE 5: Application Command Request (ALIVE:DONE)
Line 11338: 2015-12-03 22:33:45.519 [DEBUG] [ApplicationCommandMessageClass:56 ]- NODE 5: Incoming command class METER
Line 11339: 2015-12-03 22:33:45.519 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 5: Received Meter Request
Line 11340: 2015-12-03 22:33:45.521 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=1314.39
Line 11343: 2015-12-03 22:33:45.523 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 1314.39
Line 11346: 2015-12-03 22:33:46.741 [DEBUG] [WaveController$ZWaveSendThread:1254]- NODE 5: Sending ABORT Message = 01 03 00 16 EA
Line 11347: 2015-12-03 22:33:46.742 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 2 attempts left!
Line 11348: 2015-12-03 22:33:46.743 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11353: 2015-12-03 22:33:46.747 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 32 01 10 25 84 62
Line 11360: 2015-12-03 22:33:46.757 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11367: 2015-12-03 22:33:47.262 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 132, Status = Transmission complete and ACK received(0)
Line 11377: 2015-12-03 22:33:47.521 [DEBUG] [ApplicationCommandMessageClass:38 ]- NODE 5: Application Command Request (ALIVE:DONE)
Line 11378: 2015-12-03 22:33:47.522 [DEBUG] [ApplicationCommandMessageClass:56 ]- NODE 5: Incoming command class METER
Line 11379: 2015-12-03 22:33:47.523 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 5: Received Meter Request
Line 11380: 2015-12-03 22:33:47.524 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=1312.3
Line 11383: 2015-12-03 22:33:47.525 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 1312.3
Line 11390: 2015-12-03 22:33:47.546 [DEBUG] [WaveController$ZWaveSendThread:1287]- NODE 5: Response processed after 799ms/4110ms.
Line 11402: 2015-12-03 22:33:50.247 [DEBUG] [.z.i.config.ZWaveConfiguration:753 ]- NODE 5: wakeupCommandClass not supported
Line 11546: 2015-12-03 22:34:01.469 [DEBUG] [c.ZWaveAssociationCommandClass:251 ]- NODE 5: Creating new message for application command ASSOCIATIONCMD_SET
Line 11547: 2015-12-03 22:34:01.471 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 11552: 2015-12-03 22:34:01.477 [DEBUG] [c.ZWaveAssociationCommandClass:293 ]- NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 3
Line 11553: 2015-12-03 22:34:01.478 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 85 01 03 01 25 85 C0
Line 11554: 2015-12-03 22:34:01.479 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 11557: 2015-12-03 22:34:01.486 [DEBUG] [z.internal.ZWaveNetworkMonitor:267 ]- NODE 5: Starting heal
Line 11564: 2015-12-03 22:34:01.494 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11571: 2015-12-03 22:34:02.686 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 133, Status = Transmission complete and ACK received(0)
Line 11578: 2015-12-03 22:34:02.690 [DEBUG] [WaveController$ZWaveSendThread:1287]- NODE 5: Response processed after 1210ms/4110ms.
Line 11581: 2015-12-03 22:34:02.693 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 86 C7
Line 11588: 2015-12-03 22:34:02.704 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11592: 2015-12-03 22:34:07.696 [DEBUG] [WaveController$ZWaveSendThread:1254]- NODE 5: Sending ABORT Message = 01 03 00 16 EA
Line 11593: 2015-12-03 22:34:07.697 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 2 attempts left!
Line 11594: 2015-12-03 22:34:07.698 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11599: 2015-12-03 22:34:07.702 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 87 C6
Line 11606: 2015-12-03 22:34:07.712 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11609: 2015-12-03 22:34:09.764 [DEBUG] [c.ZWaveAssociationCommandClass:251 ]- NODE 5: Creating new message for application command ASSOCIATIONCMD_SET
Line 11610: 2015-12-03 22:34:09.766 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 11613: 2015-12-03 22:34:09.771 [DEBUG] [c.ZWaveAssociationCommandClass:293 ]- NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 2
Line 11614: 2015-12-03 22:34:09.773 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 11617: 2015-12-03 22:34:09.779 [DEBUG] [z.internal.ZWaveNetworkMonitor:267 ]- NODE 5: Starting heal
Line 11622: 2015-12-03 22:34:12.705 [DEBUG] [WaveController$ZWaveSendThread:1254]- NODE 5: Sending ABORT Message = 01 03 00 16 EA
Line 11623: 2015-12-03 22:34:12.706 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 1 attempts left!
Line 11624: 2015-12-03 22:34:12.706 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11629: 2015-12-03 22:34:12.710 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 8A CB
Line 11636: 2015-12-03 22:34:12.720 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11645: 2015-12-03 22:34:16.358 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 138, Status = Transmission complete and ACK received(0)
Line 11649: 2015-12-03 22:34:17.711 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
Line 11650: 2015-12-03 22:34:17.712 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11655: 2015-12-03 22:34:17.717 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 8B CA
Line 11662: 2015-12-03 22:34:17.726 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11671: 2015-12-03 22:34:20.806 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 139, Status = Transmission complete and ACK received(0)
Line 11675: 2015-12-03 22:34:22.719 [WARN ] [WaveController$ZWaveSendThread:1278]- NODE 5: Too many retries. Discarding message: Message: class = SendData (0x13), type = Request (0x00), payload = 05 03 85 02 03
Line 11678: 2015-12-03 22:34:22.723 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0B 00 13 05 04 85 01 02 01 25 88 CC
Line 11685: 2015-12-03 22:34:22.736 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11693: 2015-12-03 22:34:24.960 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 136, Status = Transmission complete and ACK received(0)
Line 11700: 2015-12-03 22:34:24.964 [DEBUG] [WaveController$ZWaveSendThread:1287]- NODE 5: Response processed after 2241ms/4110ms.
Line 11703: 2015-12-03 22:34:24.967 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 89 C9
Line 11710: 2015-12-03 22:34:24.977 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11717: 2015-12-03 22:34:28.623 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 137, Status = Transmission complete and ACK received(0)
Line 11722: 2015-12-03 22:34:29.968 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 2 attempts left!
Line 11723: 2015-12-03 22:34:29.969 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11728: 2015-12-03 22:34:29.974 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 8C CC
Line 11735: 2015-12-03 22:34:29.987 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11742: 2015-12-03 22:34:33.096 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 140, Status = Transmission complete and ACK received(0)
Line 11747: 2015-12-03 22:34:34.976 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 1 attempts left!
Line 11748: 2015-12-03 22:34:34.977 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11753: 2015-12-03 22:34:34.980 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 8D CD
Line 11760: 2015-12-03 22:34:34.990 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11767: 2015-12-03 22:34:38.303 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 141, Status = Transmission complete and ACK received(0)
Line 11786: 2015-12-03 22:34:39.981 [ERROR] [WaveController$ZWaveSendThread:1269]- NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
Line 11787: 2015-12-03 22:34:39.982 [ERROR] [b.z.i.p.s.SendDataMessageClass:158 ]- NODE 5: Got an error while sending data. Resending message.
Line 11792: 2015-12-03 22:34:39.986 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 8E CE
Line 11799: 2015-12-03 22:34:39.997 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 11806: 2015-12-03 22:34:44.444 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 142, Status = Transmission complete and ACK received(0)
Line 11811: 2015-12-03 22:34:44.988 [WARN ] [WaveController$ZWaveSendThread:1278]- NODE 5: Too many retries. Discarding message: Message: class = SendData (0x13), type = Request (0x00), payload = 05 03 85 02 02
Line 11817: 2015-12-03 22:34:49.743 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 142, Status = Transmission complete and ACK received(0)
Line 11829: 2015-12-03 22:34:54.078 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 142, Status = Transmission complete and ACK received(0)
Line 11839: 2015-12-03 22:34:54.123 [DEBUG] [ApplicationCommandMessageClass:38 ]- NODE 5: Application Command Request (ALIVE:DONE)
Line 11840: 2015-12-03 22:34:54.124 [DEBUG] [ApplicationCommandMessageClass:56 ]- NODE 5: Incoming command class ASSOCIATION
Line 11841: 2015-12-03 22:34:54.124 [DEBUG] [c.ZWaveAssociationCommandClass:96 ]- NODE 5: Received Association Request
Line 11842: 2015-12-03 22:34:54.125 [DEBUG] [c.ZWaveAssociationCommandClass:156 ]- NODE 5: association group 1 has max associations 5
Line 11843: 2015-12-03 22:34:54.126 [DEBUG] [c.ZWaveAssociationCommandClass:165 ]- NODE 5: association group 1 includes the following nodes:
Line 11851: 2015-12-03 22:34:54.555 [DEBUG] [.b.z.i.p.i.ZWaveNodeSerializer:107 ]- NODE 5: Serializing to file /var/lib/openhab/zwave/node5.xml
Line 14428: 2015-12-03 22:38:44.126 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:68 ]- NODE 5: Generating poll message for METER, endpoint 0
Line 14430: 2015-12-03 22:38:44.128 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:284 ]- NODE 5: Creating new message for application command METER_GET
Line 14434: 2015-12-03 22:38:44.130 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 14749: 2015-12-03 22:38:49.362 [DEBUG] [.o.b.z.i.c.ZWaveMeterConverter:68 ]- NODE 5: Generating poll message for METER, endpoint 0
Line 14750: 2015-12-03 22:38:49.362 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:284 ]- NODE 5: Creating new message for application command METER_GET
Line 14751: 2015-12-03 22:38:49.363 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 5: Creating empty message of class = SendData (0x13), type = Request (0x00)
Line 15139: 2015-12-03 22:39:04.713 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 32 01 10 25 9C 7A
Line 15146: 2015-12-03 22:39:04.725 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 15154: 2015-12-03 22:39:05.923 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 156, Status = Transmission complete and ACK received(0)
Line 15164: 2015-12-03 22:39:05.972 [DEBUG] [ApplicationCommandMessageClass:38 ]- NODE 5: Application Command Request (ALIVE:DONE)
Line 15165: 2015-12-03 22:39:05.973 [DEBUG] [ApplicationCommandMessageClass:56 ]- NODE 5: Incoming command class METER
Line 15166: 2015-12-03 22:39:05.974 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 5: Received Meter Request
Line 15167: 2015-12-03 22:39:05.976 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=905.41
Line 15170: 2015-12-03 22:39:05.978 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 905.41
Line 15177: 2015-12-03 22:39:05.988 [DEBUG] [WaveController$ZWaveSendThread:1287]- NODE 5: Response processed after 1274ms/4110ms.
Line 15898: 2015-12-03 22:39:26.644 [DEBUG] [WaveController$ZWaveSendThread:1228]- NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 32 01 10 25 A7 41
Line 15905: 2015-12-03 22:39:26.655 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38 ]- NODE 5: Sent Data successfully placed on stack.
Line 15912: 2015-12-03 22:39:27.851 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74 ]- NODE 5: SendData Request. CallBack ID = 167, Status = Transmission complete and ACK received(0)
Line 15922: 2015-12-03 22:39:27.898 [DEBUG] [ApplicationCommandMessageClass:38 ]- NODE 5: Application Command Request (ALIVE:DONE)
Line 15923: 2015-12-03 22:39:27.899 [DEBUG] [ApplicationCommandMessageClass:56 ]- NODE 5: Incoming command class METER
Line 15924: 2015-12-03 22:39:27.899 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:109 ]- NODE 5: Received Meter Request
Line 15925: 2015-12-03 22:39:27.900 [DEBUG] [z.i.p.c.ZWaveMeterCommandClass:157 ]- NODE 5: Meter: Type=Electric(1), Scale=W(2), Value=907.94
Line 15928: 2015-12-03 22:39:27.902 [DEBUG] [.z.internal.ZWaveActiveBinding:450 ]- NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = METER, value = 907.94
Line 15935: 2015-12-03 22:39:27.908 [DEBUG] [WaveController$ZWaveSendThread:1287]- NODE 5: Response processed after 1264ms/4110ms.

Thanks for any help or direction you can provide, I’ve been pulling my hair out trying to figure this out.

rooflz · December 4, 2015, 4:56am

In the ‘Configuration Parameters’ for the HEM I did find out how to setup the interval that the reports are sent out, as well as what reports are sent out. I have set ‘Group 1’ to send out only wattage reports for the whole device every 10 seconds. I then added my z-stick (node 1) to the ‘Group 1’ association group. After restarting everything I’m still running into the same issue.

patrik_gfeller · December 4, 2015, 6:36pm

Hi,

not sure if I have the exact same device - but maybe my configuration helps.

Items:

kind regards,
Patrik

JjS · December 4, 2015, 8:28pm

I have parameter 101 set to 16142 (send both W and kWh for all clamps) and I put my Z-stick in assoc 1.

In my configuration kWh reading is three times higher than the utility company reading, so I have a rule to recalculate it.

bakir · December 4, 2015, 9:23pm

I have:
Number Energy_HomeW "Home Energy Usage [%.2f W]" { zwave="5:command=METER,meter_scale=E_W,refresh_interval=60" }
it works fine.
see if adding ,refresh_interval=60 to the item does anything for you

deepstop · December 5, 2015, 6:45pm

I am using an Aeon Labs Smart Switch 6 (my first ZWave device) and I had it to the point where I could turn it on and off, and it was reporting Volts and KwH, but not Watts or Amps. I added refresh_interval=120 to each items and now Watts and Amps are there.

Thanks for the tip.

Chris