- 2 Aeon labs multi sensor - 4 Fibaro in wall switch - 5 POP switch - 6 Everspring SP103 motion sensor Initialization Error 1 ---------------------- 2015-11-28 09:38:06.7925 Debug Adding Interface Media.CameraInput 2015-11-28 09:38:06.8010 Debug Setting Interface options 2015-11-28 09:38:06.8410 Debug Adding Interface Protocols.UPnP 2015-11-28 09:38:06.8459 Debug Setting Interface options 2015-11-28 09:38:06.8556 Error System.ArgumentNullException: Argument cannot be null. Parameter name: type at System.Activator.CheckType (System.Type type) [0x00000] in :0 at System.Activator.CreateInstance (System.Type type at System.Activator.CreateInstance (System.Type type) [0x00000] in :0 at MIG.MigService.AddInterface (System.String domain 2015-11-28 09:38:06.9050 Error System.ArgumentNullException: Argument cannot be null. Parameter name: type at System.Activator.CheckType (System.Type type) [0x00000] in :0 at System.Activator.CreateInstance (System.Type type at System.Activator.CreateInstance (System.Type type) [0x00000] in :0 at MIG.MigService.AddInterface (System.String domain "2015-11-28 09:38:13.6276 Info HomeAutomation.HomeGenie.Automation 6 Automation Program Program.Status Idle" "2015-11-28 09:38:13.6953 Info HomeAutomation.HomeGenie.Automation 7 Automation Program Program.Status Idle" "2015-11-28 09:38:13.7009 Info HomeAutomation.HomeGenie.Automation 16 Automation Program Program.Status Idle" "2015-11-28 09:38:13.7341 Info HomeAutomation.HomeGenie.Automation 26 Automation Program Program.Status Idle" Initialization Error 2 ---------------------- "2015-11-28 09:38:38.5034 Info HomeAutomation.HomeGenie.Automation 1007 Automation Program Program.Status Running" "2015-11-28 09:38:38.5280 Info HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Running" "2015-11-28 09:38:38.5638 Info HomeAutomation.HomeGenie.Automation 1021 Automation Program Program.Status Idle" "2015-11-28 09:38:38.6016 Error HomeAutomation.HomeGenie InterfaceControl Object reference not set to an instance of an object Exception.StackTrace at ZWaveLib.CommandClasses.Basic.Set (ZWaveLib.ZWaveNode node" at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in :0 at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in :0 "2015-11-28 09:38:38.8773 Info HomeAutomation.HomeGenie.Automation 1032 Automation Program Program.Status Idle" "2015-11-28 09:38:39.0237 Error HomeAutomation.HomeGenie InterfaceControl Object reference not set to an instance of an object Exception.StackTrace at ZWaveLib.CommandClasses.Basic.Set (ZWaveLib.ZWaveNode node" at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in :0 at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in :0 "2015-11-28 09:38:39.0347 Info HomeAutomation.HomeGenie.Automation 1036 Automation Program Program.Status Idle" 2015-11-28 09:38:39.1562 Debug 04-6D 2015-11-28 09:38:39.2085 Warn Interface I/O error 2015-11-28 09:38:39.2091 Debug 06-62 2015-11-28 09:38:39.2091 Warn Interface I/O error 2015-11-28 09:38:39.2145 Debug Enabling Interface HomeAutomation.ZWave "2015-11-28 09:38:39.3355 Info HomeAutomation.X10 A8 - Status.Level 1" 2015-11-28 09:38:39.3548 Debug 04-6A 2015-11-28 09:38:39.3548 Warn Interface I/O error 2015-11-28 09:38:39.3548 Debug 06-62 2015-11-28 09:38:39.3548 Warn Interface I/O error "2015-11-28 09:38:39.3565 Info HomeAutomation.X10 A4 - Status.Level 1" 2015-11-28 09:38:39.3700 Debug 04-6F 2015-11-28 09:38:39.3700 Warn Interface I/O error 2015-11-28 09:38:39.3700 Debug 06-62 2015-11-28 09:38:39.3908 Warn Interface I/O error "2015-11-28 09:38:39.3918 Info HomeAutomation.X10 A10 - Status.Level 1" "2015-11-28 09:38:39.3819 Error HomeAutomation.HomeGenie InterfaceControl Object reference not set to an instance of an object Exception.StackTrace at ZWaveLib.CommandClasses.Basic.Set (ZWaveLib.ZWaveNode node" at MIG.Interfaces.HomeAutomation.ZWave.InterfaceControl (MIG.MigInterfaceCommand request) [0x00000] in :0 at HomeGenie.Service.HomeGenieService.InterfaceControl (MIG.MigInterfaceCommand cmd) [0x00000] in :0 "2015-11-28 09:38:39.4037 Info HomeAutomation.HomeGenie.Automation 1007 Automation Program Program.Status Idle" 2015-11-28 09:38:39.4447 Debug 04-63 2015-11-28 09:38:39.4451 Warn Interface I/O error 2015-11-28 09:38:39.4451 Debug 06-62 2015-11-28 09:38:39.4451 Warn Interface I/O error "2015-11-28 09:38:39.4466 Info HomeAutomation.X10 A11 - Status.Level 1" 2015-11-28 09:38:39.4846 Debug 04-6B 2015-11-28 09:38:39.4850 Warn Interface I/O error 2015-11-28 09:38:39.4850 Debug 06-62 2015-11-28 09:38:39.4850 Warn Interface I/O error "2015-11-28 09:38:39.4865 Info HomeAutomation.X10 A12 - Status.Level 1" "2015-11-28 09:38:39.5772 Info HomeAutomation.HomeGenie.Automation 1002 Automation Program Program.Status Idle" 2015-11-28 09:38:39.8259 Debug Disabling Interface HomeAutomation.Insteon 2015-11-28 09:38:39.8319 Debug Enabling Interface HomeAutomation.X10 2015-11-28 09:38:39.9937 Info HomeAutomation.X10 2015-11-28 09:38:40.2011 Debug True "2015-11-28 09:38:37.1465 Info HomeAutomation.HomeGenie.Automation 1018 Automation Program Program.Status Running" "2015-11-28 09:38:37.4796 Info HomeAutomation.HomeGenie.Automation 1032 Automation Program Program.Status Running" Discovery --------- 2015-11-28 09:38:43.9357 Debug DiscoveryStart "2015-11-28 09:38:44.0832 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Discovery Started" 2015-11-28 09:38:44.0923 Trace Getting protocol info for node 2 2015-11-28 09:38:44.0936 Trace Getting protocol info for node 4 2015-11-28 09:38:44.0943 Trace Getting protocol info for node 5 2015-11-28 09:38:44.0951 Trace Getting protocol info for node 6 2015-11-28 09:38:44.0960 Trace Querying/Updating node 2 2015-11-28 09:38:44.1269 Debug 2 NodeInfo 04 07 01 86 72 5E 59 85 73 71 84 80 30 31 70 98 7A EF 5A 82 "2015-11-28 09:38:44.3615 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.NodeInfo 04 07 01 86 72 5E 59 85 73 71 84 80 30 31 70 98 7A EF 5A 82" 2015-11-28 09:38:44.5273 Debug 2 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo "2015-11-28 09:38:44.6234 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.ManufacturerSpecific 0086:0002:004A" 2015-11-28 09:38:44.6490 Debug 2 NodeUpdated "2015-11-28 09:38:44.7635 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 2" 2015-11-28 09:38:44.7642 Trace Querying/Updating node 4 2015-11-28 09:38:44.7656 Debug 4 NodeInfo 04 10 01 72 86 70 85 8E 60 25 27 7A 73 EF 25 60 "2015-11-28 09:38:44.7810 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.NodeInfo 04 10 01 72 86 70 85 8E 60 25 27 7A 73 EF 25 60" "2015-11-28 09:38:44.9398 Info HomeAutomation.HomeGenie.Automation 81 Automation Program Program.Status Setup" 2015-11-28 09:38:45.4048 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:45.4055 Trace WaitAck 2015-11-28 09:38:45.4063 Trace Sending Message (Node=4 2015-11-28 09:38:45.4130 Debug 01-0A-00-13-04-03-86-13-EF-05-03-9D 2015-11-28 09:38:45.4165 Debug 06 2015-11-28 09:38:45.5174 Debug 01-04-01-13-01-E8 2015-11-28 09:38:45.7229 Debug 06 2015-11-28 09:38:45.7229 Trace SendDataReady 2015-11-28 09:38:45.7328 Debug 01-07-00-13-03-00-00-02-EA 2015-11-28 09:38:45.7339 Debug 06 2015-11-28 09:38:45.8167 Trace Complete 2015-11-28 09:38:45.8232 Trace [[[ END REQUEST ]]] took 417 ms 2015-11-28 09:38:45.9251 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:45.9251 Trace WaitAck 2015-11-28 09:38:45.9260 Trace Sending Message (Node=4 2015-11-28 09:38:45.9260 Debug 01-0A-00-13-04-03-86-13-25-05-04-50 2015-11-28 09:38:46.0259 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:46.0268 Debug 06 2015-11-28 09:38:46.0268 Trace SendDataReady 2015-11-28 09:38:46.1280 Debug 01-07-00-13-04-00-00-01-EE 2015-11-28 09:38:46.1289 Debug 06 2015-11-28 09:38:46.1304 Trace Complete 2015-11-28 09:38:46.1315 Trace [[[ END REQUEST ]]] took 205 ms 2015-11-28 09:38:46.1333 Debug 01-0A-00-04-00-04-04-86-14-25-01-47 2015-11-28 09:38:46.1363 Debug 06 2015-11-28 09:38:46.6067 Debug 4 VersionCommandClass ZWaveLib.Values.VersionValue "2015-11-28 09:38:46.6136 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Version.SwitchBinary 1" 2015-11-28 09:38:46.7324 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:46.7330 Trace WaitAck 2015-11-28 09:38:46.7330 Trace Sending Message (Node=4 2015-11-28 09:38:46.7342 Debug 01-0A-00-13-04-03-86-13-60-05-05-14 2015-11-28 09:38:46.8455 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:46.8464 Debug 06 2015-11-28 09:38:46.8464 Trace SendDataReady 2015-11-28 09:38:46.9476 Debug 01-07-00-13-05-00-00-02-EC 2015-11-28 09:38:46.9712 Debug 06 2015-11-28 09:38:46.9712 Trace Complete 2015-11-28 09:38:46.9793 Trace [[[ END REQUEST ]]] took 246 ms 2015-11-28 09:38:46.9800 Debug 01-0A-00-04-00-04-04-86-14-60-02-01 2015-11-28 09:38:46.9800 Debug 4 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo 2015-11-28 09:38:46.9894 Debug 06 2015-11-28 09:38:46.9900 Debug 4 VersionCommandClass ZWaveLib.Values.VersionValue 2015-11-28 09:38:47.0036 Debug 4 NodeUpdated "2015-11-28 09:38:47.0056 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.ManufacturerSpecific 010F:0400:100A" 2015-11-28 09:38:47.0077 Trace Querying/Updating node 5 2015-11-28 09:38:47.0081 Debug 5 NodeInfo 04 10 01 20 25 27 70 75 86 72 85 87 77 "2015-11-28 09:38:47.0097 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Version.MultiInstance 2" "2015-11-28 09:38:47.0116 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 4" 2015-11-28 09:38:47.0145 Debug 5 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo "2015-11-28 09:38:47.0175 Info HomeAutomation.ZWave 5 ZWave Node ZWaveNode.NodeInfo 04 10 01 20 25 27 70 75 86 72 85 87 77" "2015-11-28 09:38:47.0273 Info HomeAutomation.ZWave 5 ZWave Node ZWaveNode.ManufacturerSpecific 0154:1100:0001" 2015-11-28 09:38:47.0286 Debug 5 NodeUpdated "2015-11-28 09:38:47.0497 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 5" 2015-11-28 09:38:47.0507 Trace Querying/Updating node 6 2015-11-28 09:38:47.0511 Debug 6 NodeInfo 04 20 01 30 70 84 72 86 85 80 71 "2015-11-28 09:38:47.0820 Info HomeAutomation.ZWave 6 ZWave Node ZWaveNode.NodeInfo 04 20 01 30 70 84 72 86 85 80 71" 2015-11-28 09:38:47.5811 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:47.5811 Trace WaitAck 2015-11-28 09:38:47.5820 Trace Sending Message (Node=6 2015-11-28 09:38:47.5820 Debug 01-0A-00-13-06-03-86-13-04-05-06-71 2015-11-28 09:38:47.6158 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:47.6158 Debug 06 2015-11-28 09:38:47.6172 Trace SendDataReady "2015-11-28 09:38:48.4068 Info HomeGenie.UpdateChecker 0 HomeGenie Update Checker InstallProgress.Update STARTED" 2015-11-28 09:38:49.1377 Debug 01-07-00-13-06-01-00-91-7D 2015-11-28 09:38:49.1386 Debug 06 2015-11-28 09:38:49.1391 Trace Error 2015-11-28 09:38:49.1402 Trace [[[ END REQUEST ]]] took 1558 ms 2015-11-28 09:38:49.1446 Warn Could not deliver message to Node 6 (CallbackId=06 2015-11-28 09:38:49.2453 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:49.2453 Trace WaitAck 2015-11-28 09:38:49.2462 Trace Sending Message (Node=6 2015-11-28 09:38:49.2469 Debug 01-0A-00-13-06-03-86-13-04-05-06-71 2015-11-28 09:38:49.3435 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:49.3444 Debug 06 2015-11-28 09:38:49.3444 Trace SendDataReady 2015-11-28 09:38:50.5831 Debug 01-07-00-13-06-01-00-81-6D 2015-11-28 09:38:50.5863 Debug 06 2015-11-28 09:38:50.5868 Trace Error 2015-11-28 09:38:50.5952 Trace [[[ END REQUEST ]]] took 1349 ms 2015-11-28 09:38:50.5952 Warn Could not deliver message to Node 6 (CallbackId=06 2015-11-28 09:38:50.6964 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:50.6969 Trace WaitAck 2015-11-28 09:38:50.6969 Trace Sending Message (Node=6 2015-11-28 09:38:50.6982 Debug 01-0A-00-13-06-03-86-13-04-05-06-71 2015-11-28 09:38:50.7990 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:50.8000 Debug 06 2015-11-28 09:38:50.8000 Trace SendDataReady "2015-11-28 09:38:46.9101 Info HomeAutomation.HomeGenie.Automation 93 Automation Program Program.Status Setup" "2015-11-28 09:38:50.4377 Info HomeAutomation.HomeGenie.Automation 145 Automation Program Program.Status Setup" "2015-11-28 09:38:48.9833 Info HomeAutomation.HomeGenie.Automation 1039 Automation Program Program.Status Setup" 2015-11-28 09:38:52.1251 Debug 01-07-00-13-06-01-00-8C-60 2015-11-28 09:38:52.1269 Debug 06 2015-11-28 09:38:52.1275 Trace Error 2015-11-28 09:38:52.1290 Trace [[[ END REQUEST ]]] took 1432 ms 2015-11-28 09:38:52.1339 Warn Delivery of message to Node 6 failed (CallbackId=06). 2015-11-28 09:38:52.1339 Debug 6 Error "2015-11-28 09:38:52.1676 Info HomeAutomation.ZWave 6 Z-Wave Node Status.Error Response timeout!" 2015-11-28 09:38:52.1708 Warn Node is flagged as sleeping 2015-11-28 09:38:52.3645 Trace Adding message 01-0A-00-13-06-03-86-13-04-05-06-71 2015-11-28 09:38:52.4879 Debug 6 WakeUpSleepingStatus 1 "2015-11-28 09:38:52.5585 Info HomeAutomation.ZWave 6 ZWave Node ZWaveNode.WakeUpSleepingStatus 1" 2015-11-28 09:38:52.6585 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:52.6590 Trace WaitAck 2015-11-28 09:38:52.6590 Trace Sending Message (Node=6 2015-11-28 09:38:52.6602 Debug 01-0A-00-13-06-03-86-13-20-05-07-54 2015-11-28 09:38:52.6645 Debug 06 "2015-11-28 09:38:52.6790 Info HomeAutomation.HomeGenie.Automation 8 Automation Program Program.Status Setup" 2015-11-28 09:38:52.7653 Debug 01-04-01-13-01-E8 2015-11-28 09:38:52.7663 Debug 06 2015-11-28 09:38:52.7663 Trace SendDataReady "2015-11-28 09:38:52.8455 Info HomeAutomation.HomeGenie.Automation 93 Automation Program Program.Status Idle" "2015-11-28 09:38:52.8834 Info HomeAutomation.HomeGenie.Automation 93 Automation Program Program.Status Running" "2015-11-28 09:38:53.7255 Info HomeAutomation.HomeGenie.Automation 93 Automation Program Program.Status Background" "2015-11-28 09:38:53.7487 Info HomeGenie.UpdateChecker 0 HomeGenie Update Checker InstallProgress.Update COMPLETED" 2015-11-28 09:38:53.9769 Debug 01-07-00-13-07-01-00-81-6C 2015-11-28 09:38:53.9769 Debug 06 2015-11-28 09:38:53.9801 Trace Error 2015-11-28 09:38:53.9814 Trace [[[ END REQUEST ]]] took 1322 ms 2015-11-28 09:38:53.9819 Warn Could not deliver message to Node 6 (CallbackId=07 2015-11-28 09:38:54.1191 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:54.1191 Trace WaitAck 2015-11-28 09:38:54.1205 Trace Sending Message (Node=6 2015-11-28 09:38:54.1215 Debug 01-0A-00-13-06-03-86-13-20-05-07-54 2015-11-28 09:38:54.2073 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:54.2083 Debug 06 2015-11-28 09:38:54.2094 Trace SendDataReady "2015-11-28 09:38:54.4797 Info HomeAutomation.HomeGenie.Automation 180 Automation Program Program.Status Setup" 2015-11-28 09:38:55.5035 Debug 01-07-00-13-07-01-00-8A-67 2015-11-28 09:38:55.5046 Debug 06 2015-11-28 09:38:55.5098 Trace Error 2015-11-28 09:38:55.5153 Trace [[[ END REQUEST ]]] took 1395 ms 2015-11-28 09:38:55.5153 Warn Could not deliver message to Node 6 (CallbackId=07 2015-11-28 09:38:55.6204 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:38:55.6208 Trace WaitAck 2015-11-28 09:38:55.6732 Trace Sending Message (Node=6 2015-11-28 09:38:55.6742 Debug 01-0A-00-13-06-03-86-13-20-05-07-54 2015-11-28 09:38:55.7456 Debug 06-01-04-01-13-01-E8 2015-11-28 09:38:55.7466 Debug 06 2015-11-28 09:38:55.7475 Trace SendDataReady "2015-11-28 09:38:55.8494 Info HomeAutomation.HomeGenie.Automation 145 Automation Program Program.Status Idle" "2015-11-28 09:38:55.8540 Info HomeAutomation.HomeGenie.Automation 145 Automation Program Program.Status Running" "2015-11-28 09:38:56.0285 Info HomeGenie.System 0 HomeGenie System HomeGenie.Status STARTED" 2015-11-28 09:38:57.0689 Debug 01-07-00-13-07-01-00-8A-67 2015-11-28 09:38:57.0698 Debug 06 2015-11-28 09:38:57.0698 Trace Error 2015-11-28 09:38:57.0722 Warn Delivery of message to Node 6 failed (CallbackId=07). 2015-11-28 09:38:57.0740 Debug 6 Error 2015-11-28 09:38:57.0717 Trace [[[ END REQUEST ]]] took 1450 ms 2015-11-28 09:38:57.0740 Warn Node is flagged as sleeping 2015-11-28 09:38:57.0773 Trace Removing old message 01-0A-00-13-06-03-86-13-04-05-06-71 2015-11-28 09:38:57.0782 Trace Adding message 01-0A-00-13-06-03-86-13-01-05-08-7A 2015-11-28 09:38:57.0838 Warn Node is flagged as sleeping 2015-11-28 09:38:57.0955 Trace Removing old message 01-0A-00-13-06-03-86-13-01-05-08-7A 2015-11-28 09:38:57.0967 Trace Adding message 01-0A-00-13-06-03-86-13-30-05-09-4A "2015-11-28 09:38:57.0947 Info HomeAutomation.ZWave 6 Z-Wave Node Status.Error Response timeout!" 2015-11-28 09:38:57.0937 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1043 Trace Removing old message 01-0A-00-13-06-03-86-13-30-05-09-4A 2015-11-28 09:38:57.1050 Trace Adding message 01-0A-00-13-06-03-86-13-20-05-07-54 2015-11-28 09:38:57.1073 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1083 Trace Removing old message 01-0A-00-13-06-03-86-13-20-05-07-54 2015-11-28 09:38:57.1121 Trace Adding message 01-0A-00-13-06-03-86-13-70-05-0A-09 2015-11-28 09:38:57.1186 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1269 Trace Removing old message 01-0A-00-13-06-03-86-13-70-05-0A-09 2015-11-28 09:38:57.1329 Trace Adding message 01-0A-00-13-06-03-86-13-84-05-0B-FC 2015-11-28 09:38:57.1435 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1446 Trace Removing old message 01-0A-00-13-06-03-86-13-84-05-0B-FC 2015-11-28 09:38:57.1462 Trace Adding message 01-0A-00-13-06-03-86-13-72-05-0C-0D 2015-11-28 09:38:57.1482 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1491 Trace Removing old message 01-0A-00-13-06-03-86-13-72-05-0C-0D 2015-11-28 09:38:57.1491 Trace Adding message 01-0A-00-13-06-03-86-13-86-05-0D-F8 2015-11-28 09:38:57.1536 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1601 Trace Removing old message 01-0A-00-13-06-03-86-13-86-05-0D-F8 2015-11-28 09:38:57.1609 Trace Adding message 01-0A-00-13-06-03-86-13-85-05-0E-F8 2015-11-28 09:38:57.1790 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1799 Trace Removing old message 01-0A-00-13-06-03-86-13-85-05-0E-F8 2015-11-28 09:38:57.1799 Trace Adding message 01-0A-00-13-06-03-86-13-80-05-0F-FC 2015-11-28 09:38:57.1819 Warn Node is flagged as sleeping 2015-11-28 09:38:57.1831 Trace Removing old message 01-0A-00-13-06-03-86-13-80-05-0F-FC 2015-11-28 09:38:57.1852 Trace Adding message 01-0A-00-13-06-03-86-13-71-05-10-12 2015-11-28 09:38:57.1885 Debug 6 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo "2015-11-28 09:38:57.2050 Info HomeAutomation.ZWave 6 ZWave Node ZWaveNode.ManufacturerSpecific 0060:0101:0001" 2015-11-28 09:38:57.2059 Debug 6 NodeUpdated "2015-11-28 09:38:57.2430 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Updated node 6" 2015-11-28 09:38:58.4723 Debug DiscoveryEnd 2015-11-28 09:38:58.4864 Info HomeAutomation.ZWave "2015-11-28 09:38:58.4872 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Discovery Complete" Node 4 level poll ----------------- 2015-11-28 09:39:29.0058 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:39:29.0058 Trace WaitAck 2015-11-28 09:39:29.0058 Trace Sending Message (Node=4 2015-11-28 09:39:29.0078 Debug 01-09-00-13-04-02-20-02-05-11-D5 2015-11-28 09:39:29.0775 Debug 06-01-04-01-13-01-E8 2015-11-28 09:39:29.0785 Debug 06 2015-11-28 09:39:29.0793 Trace SendDataReady 2015-11-28 09:39:29.0804 Debug 01-07-00-13-11-00-00-01-FB 2015-11-28 09:39:29.0816 Debug 06 2015-11-28 09:39:29.0832 Trace Complete 2015-11-28 09:39:29.0934 Trace [[[ END REQUEST ]]] took 87 ms 2015-11-28 09:39:29.0956 Debug 01-09-00-04-00-04-03-20-03-00-D6 2015-11-28 09:39:29.0968 Debug 06 2015-11-28 09:39:29.0996 Debug 4 Level 0 "2015-11-28 09:39:29.1287 Info HomeAutomation.ZWave 4 ZWave Node Status.Level 0" "2015-11-28 09:39:29.1301 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Basic 0" Node 2 wakeup ------------- 2015-11-28 09:47:06.0960 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 09:47:06.0968 Debug 06 2015-11-28 09:47:06.1062 Debug 2 WakeUpNotify 1 "2015-11-28 09:47:06.1098 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" "2015-11-28 09:47:06.6165 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" "2015-11-28 09:47:07.0595 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/ [CLOSED AFTER 0.066 seconds]" "2015-11-28 09:47:06.9916 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/ [OPEN]" 2015-11-28 09:47:07.0824 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:47:07.0830 Trace WaitAck 2015-11-28 09:47:07.0830 Trace Sending Message (Node=2 2015-11-28 09:47:07.0842 Debug 01-09-00-13-02-02-80-02-05-13-71 2015-11-28 09:47:07.1559 Debug 06-01-04-01-13-01-E8 2015-11-28 09:47:07.1568 Debug 06 2015-11-28 09:47:07.1568 Trace SendDataReady 2015-11-28 09:47:07.2580 Debug 01-07-00-13-13-00-00-02-FA 2015-11-28 09:47:07.2590 Debug 06 2015-11-28 09:47:07.2590 Trace Complete 2015-11-28 09:47:07.2605 Trace [[[ END REQUEST ]]] took 177 ms 2015-11-28 09:47:07.8635 Debug 01-09-00-04-00-02-03-80-03-52-22 2015-11-28 09:47:07.8646 Debug 06 2015-11-28 09:47:07.8696 Debug 2 Battery 82 "2015-11-28 09:47:07.8726 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 82" "2015-11-28 09:47:07.8775 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 82" Node 4 level set ---------------- "2015-11-28 09:49:04.0009 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Control.On/null/ [OPEN]" "2015-11-28 09:49:04.0397 Info HomeAutomation.ZWave 4 ZWave Node Status.Level 1" "2015-11-28 09:49:04.1648 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Control.On/null/ [CLOSED AFTER 0.164 seconds]" 2015-11-28 09:49:04.4960 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:04.4960 Trace WaitAck 2015-11-28 09:49:04.4973 Trace Sending Message (Node=4 CallbackId=14 Function=SendData CommandClass=Basic) 2015-11-28 09:49:04.4997 Debug 01-0A-00-13-04-03-20-01-FF-05-14-2E 2015-11-28 09:49:04.5540 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:04.5556 Debug 06 2015-11-28 09:49:04.5564 Trace SendDataReady 2015-11-28 09:49:04.5574 Debug 01-07-00-13-14-00-00-02-FD 2015-11-28 09:49:04.5596 Debug 06 2015-11-28 09:49:04.5602 Trace Complete 2015-11-28 09:49:04.5613 Trace [[[ END REQUEST ]]] took 64 ms Node 4 get manufacturer specific code ------------------------------------- "2015-11-28 09:49:17.6450 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/ManufacturerSpecific.Get/?_=1448699940788 [OPEN]" 2015-11-28 09:49:17.7142 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:17.7142 Trace WaitAck 2015-11-28 09:49:17.7152 Trace Sending Message (Node=4 2015-11-28 09:49:17.7158 Debug 01-09-00-13-04-02-72-04-05-15-85 2015-11-28 09:49:17.7310 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:17.7319 Debug 06 2015-11-28 09:49:17.7319 Trace SendDataReady 2015-11-28 09:49:17.8335 Debug 01-07-00-13-15-00-00-02-FC 2015-11-28 09:49:17.8348 Debug 06 2015-11-28 09:49:17.8348 Trace Complete 2015-11-28 09:49:17.8367 Trace [[[ END REQUEST ]]] took 121 ms 2015-11-28 09:49:17.8377 Debug 01-0E-00-04-00-04-08-72-05-01-0F-04-00-10-0A-9E 2015-11-28 09:49:17.8388 Debug 06 2015-11-28 09:49:17.8462 Debug 4 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo "2015-11-28 09:49:17.8487 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.ManufacturerSpecific 010F:0400:100A" Node 4 get other parameter values --------------------------------- "2015-11-28 09:49:18.2575 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/NodeInfo.Get/?_=1448699940789 [OPEN]" 2015-11-28 09:49:18.2634 Debug Node 4 2015-11-28 09:49:18.4378 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:18.4378 Trace WaitAck 2015-11-28 09:49:18.4388 Trace Sending Message (Node=4 CallbackId=00 Function=RequestNodeInfo CommandClass=NotSet) 2015-11-28 09:49:18.4388 Debug 01-04-00-60-04-9F 2015-11-28 09:49:18.4516 Debug 06-01-04-01-60-01-9B 2015-11-28 09:49:18.4525 Debug 06 2015-11-28 09:49:18.4536 Trace SendDataReady 2015-11-28 09:49:18.5544 Debug 01-16-00-49-84-04-10-04-10-01-72-86-70-85-8E-60-25-27-7A-73-EF-25-60-6B 2015-11-28 09:49:18.5557 Debug 06 2015-11-28 09:49:18.5725 Debug 4 NodeInfo 04 10 01 72 86 70 85 8E 60 25 27 7A 73 EF 25 60 2015-11-28 09:49:18.5747 Trace Complete 2015-11-28 09:49:18.5758 Trace [[[ END REQUEST ]]] took 137 ms "2015-11-28 09:49:18.5769 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.NodeInfo 04 10 01 72 86 70 85 8E 60 25 27 7A 73 EF 25 60" "2015-11-28 09:49:18.7286 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/NodeInfo.Get/?_=1448699940789 [CLOSED AFTER 0.471 seconds]" 2015-11-28 09:49:18.6766 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:18.6770 Trace WaitAck 2015-11-28 09:49:18.6770 Trace Sending Message (Node=4 CallbackId=16 Function=SendData CommandClass=MultiInstance) 2015-11-28 09:49:18.6782 Debug 01-0A-00-13-04-03-60-04-25-05-16-B3 2015-11-28 09:49:18.7760 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:18.7770 Debug 06 2015-11-28 09:49:18.7770 Trace SendDataReady 2015-11-28 09:49:18.8782 Debug 01-07-00-13-16-00-00-02-FF 2015-11-28 09:49:18.8795 Debug 06 2015-11-28 09:49:18.8802 Trace Complete 2015-11-28 09:49:18.8815 Trace [[[ END REQUEST ]]] took 204 ms 2015-11-28 09:49:18.8824 Debug 01-0A-00-04-00-04-04-60-05-25-02-B3 2015-11-28 09:49:18.8842 Debug 06 2015-11-28 09:49:18.8883 Debug 4 MultiinstanceSwitchBinaryCount 2 "2015-11-28 09:49:18.8907 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.MultiInstance.SwitchBinary.Count 2" "2015-11-28 09:49:19.0403 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1448699940790 [OPEN]" "2015-11-28 09:49:22.0571 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1448699940790 [CLOSED AFTER 3.017 seconds]" "2015-11-28 09:49:22.0963 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Association.Get/1/?_=1448699940791 [OPEN]" "2015-11-28 09:49:22.1277 Info WebServiceGateway 192.168.1.103 HTTP GET 304 /hg/html/ext/zwave/pepper1db/010F_0400_100A.json [CLOSED AFTER 0.007 seconds]" "2015-11-28 09:49:22.1207 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /hg/html/ext/zwave/pepper1db/010F_0400_100A.json [OPEN]" 2015-11-28 09:49:22.1363 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:22.1369 Trace WaitAck 2015-11-28 09:49:22.1369 Trace Sending Message (Node=4 CallbackId=17 Function=SendData CommandClass=Association) 2015-11-28 09:49:22.1380 Debug 01-0A-00-13-04-03-85-02-01-05-17-75 2015-11-28 09:49:22.1469 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:22.1469 Debug 06 2015-11-28 09:49:22.1481 Trace SendDataReady 2015-11-28 09:49:22.2488 Debug 01-07-00-13-17-00-00-02-FE 2015-11-28 09:49:22.2488 Debug 06 2015-11-28 09:49:22.2507 Trace Complete 2015-11-28 09:49:22.2518 Trace [[[ END REQUEST ]]] took 114 ms 2015-11-28 09:49:22.2525 Debug 01-0B-00-04-00-04-05-85-03-01-05-00-73 2015-11-28 09:49:22.2535 Debug 06 "2015-11-28 09:49:22.4648 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Association.Get/1/?_=1448699940791 [CLOSED AFTER 0.368 seconds]" 2015-11-28 09:49:22.2602 Debug 4 Association ZWaveLib.CommandClasses.Association+AssociationResponse "2015-11-28 09:49:22.2652 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Max 5" "2015-11-28 09:49:22.2689 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Count 0" "2015-11-28 09:49:22.2853 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.1 None" "2015-11-28 09:49:22.6505 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940792 [CLOSED AFTER 0.006 seconds]" "2015-11-28 09:49:22.6443 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940792 [OPEN]" "2015-11-28 09:49:28.6441 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Config.ParameterGet/1/?_=1448699940793 [CLOSED AFTER 0.38 seconds]" "2015-11-28 09:49:28.2640 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Config.ParameterGet/1/?_=1448699940793 [OPEN]" 2015-11-28 09:49:28.3581 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:28.3581 Trace WaitAck 2015-11-28 09:49:28.3590 Trace Sending Message (Node=4 CallbackId=18 Function=SendData CommandClass=Configuration) 2015-11-28 09:49:28.3590 Debug 01-0A-00-13-04-03-70-05-01-05-18-88 2015-11-28 09:49:28.3956 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:28.3964 Debug 06 2015-11-28 09:49:28.3968 Trace SendDataReady 2015-11-28 09:49:28.4976 Debug 01-07-00-13-18-00-00-02-F1 2015-11-28 09:49:28.4987 Debug 06 2015-11-28 09:49:28.4991 Trace Complete 2015-11-28 09:49:28.4999 Trace [[[ END REQUEST ]]] took 141 ms 2015-11-28 09:49:28.5008 Debug 01-0B-00-04-00-04-05-70-06-01-01-FF-78 2015-11-28 09:49:28.5018 Debug 06 2015-11-28 09:49:28.5278 Debug 4 Configuration 255 "2015-11-28 09:49:28.5344 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Variables.1 255" "2015-11-28 09:49:32.6466 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940794 [CLOSED AFTER 0.007 seconds]" "2015-11-28 09:49:32.6396 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940794 [OPEN]" 2015-11-28 09:49:33.6023 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:33.6028 Trace WaitAck 2015-11-28 09:49:33.6028 Trace Sending Message (Node=4 CallbackId=19 Function=SendData CommandClass=Basic) 2015-11-28 09:49:33.6047 Debug 01-09-00-13-04-02-20-02-05-19-DD 2015-11-28 09:49:33.6432 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:33.6441 Debug 06 2015-11-28 09:49:33.6441 Trace SendDataReady 2015-11-28 09:49:33.7453 Debug 01-07-00-13-19-00-00-02-F0 2015-11-28 09:49:33.7462 Debug 06 2015-11-28 09:49:33.7462 Trace Complete 2015-11-28 09:49:33.7476 Trace [[[ END REQUEST ]]] took 144 ms 2015-11-28 09:49:33.7484 Debug 01-09-00-04-00-04-03-20-03-FF-29 2015-11-28 09:49:33.7494 Debug 06 2015-11-28 09:49:33.7499 Debug 4 Level 255 "2015-11-28 09:49:33.7540 Info HomeAutomation.ZWave 4 ZWave Node Status.Level 1" "2015-11-28 09:49:33.7561 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Basic 255" 2015-11-28 09:49:34.3485 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:34.3490 Trace WaitAck 2015-11-28 09:49:34.3499 Trace Sending Message (Node=5 CallbackId=1A Function=SendData CommandClass=Basic) 2015-11-28 09:49:34.3499 Debug 01-09-00-13-05-02-20-02-05-1A-DF 2015-11-28 09:49:34.3570 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:34.3579 Debug 06 2015-11-28 09:49:34.3579 Trace SendDataReady 2015-11-28 09:49:34.4591 Debug 01-07-00-13-1A-00-00-02-F3 2015-11-28 09:49:34.4601 Debug 06 2015-11-28 09:49:34.4601 Trace Complete 2015-11-28 09:49:34.4613 Trace [[[ END REQUEST ]]] took 112 ms 2015-11-28 09:49:34.4622 Debug 01-09-00-04-00-05-03-20-03-00-D7 2015-11-28 09:49:34.4633 Debug 06 2015-11-28 09:49:34.4633 Debug 5 Level 0 "2015-11-28 09:49:34.4669 Info HomeAutomation.ZWave 5 ZWave Node Status.Level 0" "2015-11-28 09:49:34.4688 Info HomeAutomation.ZWave 5 ZWave Node ZWaveNode.Basic 0" 2015-11-28 09:49:41.6084 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 09:49:41.6084 Trace WaitAck 2015-11-28 09:49:41.6093 Trace Sending Message (Node=4 CallbackId=1B Function=SendData CommandClass=MultiInstance) 2015-11-28 09:49:41.6100 Debug 01-0D-00-13-04-06-60-0D-00-01-25-02-05-1B-B6 2015-11-28 09:49:41.6303 Debug 06-01-04-01-13-01-E8 2015-11-28 09:49:41.6312 Debug 06 2015-11-28 09:49:41.6312 Trace SendDataReady 2015-11-28 09:49:41.7323 Debug 01-07-00-13-1B-00-00-02-F2 2015-11-28 09:49:41.7334 Debug 06 2015-11-28 09:49:41.7338 Trace Complete 2015-11-28 09:49:41.7338 Trace [[[ END REQUEST ]]] took 125 ms 2015-11-28 09:49:41.7355 Debug 01-0D-00-04-00-04-07-60 2015-11-28 09:49:41.7382 Warn Expected message length is 15 currently received length is 8 2015-11-28 09:49:41.7388 Debug 0D-01-00-25-03-FF-40 2015-11-28 09:49:41.7411 Info Merged buffer to message: 01-0D-00-04-00-04-07-60-0D-01-00-25-03-FF-40 2015-11-28 09:49:41.7422 Debug 06 MultiChannel encapsulated message: CmdClass: 37; message: 25-03-FF "2015-11-28 09:49:41.9643 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/MultiInstance.Get/Switch.Binary/1/?_=1448699940795 [CLOSED AFTER 0.606 seconds]" "2015-11-28 09:49:41.3581 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/MultiInstance.Get/Switch.Binary/1/?_=1448699940795 [OPEN]" 2015-11-28 09:49:41.7652 Debug 4 Level 255 "2015-11-28 09:49:41.7680 Info HomeAutomation.ZWave 4 ZWave Node Status.Level.1 1" "2015-11-28 09:49:41.7708 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Basic 255" "2015-11-28 09:49:41.7852 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.MultiInstance.SwitchBinary.1 255" "2015-11-28 09:49:42.0244 Info HomeAutomation.ZWave 4.1 Virtual Module Status.Level 255" "2015-11-28 09:49:42.6416 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940796 [CLOSED AFTER 0.007 seconds]" Node 6 motion detected (ERROR: shown as luminance level 255 in Homegenie) ------------------------------------------------------------------------- 2015-11-28 10:08:40.0968 Debug 01-09-00-04-00-06-03-20-01-FF-29 2015-11-28 10:08:40.0977 Debug 06 2015-11-28 10:08:40.0983 Debug 6 Level 255 "2015-11-28 10:08:40.1017 Info HomeAutomation.ZWave 6 ZWave Node Status.Level 255" "2015-11-28 10:08:40.1040 Info HomeAutomation.ZWave 6 ZWave Node ZWaveNode.Basic 255" 2015-11-28 10:08:40.2051 Debug 01-09-00-04-00-06-03-20-01-FF-29 2015-11-28 10:08:40.2060 Debug 06 2015-11-28 10:08:40.2060 Warn Repeated message discarded. 2015-11-28 10:08:40.4073 Debug 01-09-00-04-00-06-03-20-01-FF-29 2015-11-28 10:08:40.4082 Debug 06 2015-11-28 10:08:40.4082 Warn Repeated message discarded. Node 6 level reset ------------------ "2015-11-28 10:10:13.6600 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940996 [OPEN]" "2015-11-28 10:10:13.6763 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940996 [CLOSED AFTER 0.015 seconds]" "2015-11-28 10:10:14.2999 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/System.Configure/SystemLogging.DownloadCsv/0 [OPEN]" 2015-11-28 10:10:17.1774 Debug 01-09-00-04-00-06-03-20-01-00-D6 2015-11-28 10:10:17.1783 Debug 06 2015-11-28 10:10:17.1788 Debug 6 Level 0 "2015-11-28 10:10:17.1817 Info HomeAutomation.ZWave 6 ZWave Node Status.Level 0" "2015-11-28 10:10:17.1850 Info HomeAutomation.ZWave 6 ZWave Node ZWaveNode.Basic 0" "2015-11-28 10:10:23.2778 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/System.Configure/SystemLogging.DownloadCsv/0 [CLOSED AFTER 8.978 seconds]" "2015-11-28 10:10:23.6610 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940997 [OPEN]" "2015-11-28 10:10:23.6681 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699940997 [CLOSED AFTER 0.007 seconds]" Node 6 wake-up, battery status report ------------------------------------- 2015-11-28 10:23:05.9606 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:23:05.9616 Debug 06 2015-11-28 10:23:05.9622 Debug 2 WakeUpNotify 1 "2015-11-28 10:23:05.9646 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:23:06.3548 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:23:06.3548 Trace WaitAck 2015-11-28 10:23:06.3548 Trace Sending Message (Node=2 CallbackId=24 Function=SendData CommandClass=Battery) 2015-11-28 10:23:06.3564 Debug 01-09-00-13-02-02-80-02-05-24-46 2015-11-28 10:23:06.3652 Debug 06-01-04-01-13-01-E8 2015-11-28 10:23:06.3660 Debug 06 2015-11-28 10:23:06.3660 Trace SendDataReady "2015-11-28 10:23:06.4136 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941075 [CLOSED AFTER 0.005 seconds]" "2015-11-28 10:23:06.4083 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941075 [OPEN]" "2015-11-28 10:23:06.0174 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:23:06.4672 Debug 01-07-00-13-24-00-00-02-CD 2015-11-28 10:23:06.4681 Debug 06 2015-11-28 10:23:06.4681 Trace Complete 2015-11-28 10:23:06.4694 Trace [[[ END REQUEST ]]] took 114 ms 2015-11-28 10:23:07.0711 Debug 01-09-00-04-00-02-03-80-03-52-22 2015-11-28 10:23:07.0720 Debug 06 2015-11-28 10:23:07.0720 Debug 2 Battery 82 "2015-11-28 10:23:07.0754 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 82" "2015-11-28 10:23:07.0777 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 82" Node 2 wake-up, battery status report ------------------------------------- "2015-11-28 10:23:03.7071 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941074 [OPEN]" 2015-11-28 10:23:05.9606 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:23:05.9616 Debug 06 2015-11-28 10:23:05.9622 Debug 2 WakeUpNotify 1 "2015-11-28 10:23:05.9646 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:23:06.3548 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:23:06.3548 Trace WaitAck 2015-11-28 10:23:06.3548 Trace Sending Message (Node=2 CallbackId=24 Function=SendData CommandClass=Battery) 2015-11-28 10:23:06.3564 Debug 01-09-00-13-02-02-80-02-05-24-46 2015-11-28 10:23:06.3652 Debug 06-01-04-01-13-01-E8 2015-11-28 10:23:06.3660 Debug 06 2015-11-28 10:23:06.3660 Trace SendDataReady "2015-11-28 10:23:06.4136 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941075 [CLOSED AFTER 0.005 seconds]" "2015-11-28 10:23:06.4083 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941075 [OPEN]" "2015-11-28 10:23:06.0174 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:23:06.4672 Debug 01-07-00-13-24-00-00-02-CD 2015-11-28 10:23:06.4681 Debug 06 2015-11-28 10:23:06.4681 Trace Complete 2015-11-28 10:23:06.4694 Trace [[[ END REQUEST ]]] took 114 ms 2015-11-28 10:23:07.0711 Debug 01-09-00-04-00-02-03-80-03-52-22 2015-11-28 10:23:07.0720 Debug 06 2015-11-28 10:23:07.0720 Debug 2 Battery 82 "2015-11-28 10:23:07.0754 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 82" "2015-11-28 10:23:07.0777 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 82" "2015-11-28 10:23:13.7115 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941076 [OPEN]" Node 5 level poll, incorrect message length??? ---------------------------------------------- 2015-11-28 10:29:43.5787 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:29:43.5791 Trace WaitAck 2015-11-28 10:29:43.5791 Trace Sending Message (Node=5 CallbackId=26 Function=SendData CommandClass=Basic) 2015-11-28 10:29:43.5804 Debug 01-09-00-13-05-02-20-02-05-26-E3 2015-11-28 10:29:43.5850 Debug 06 2015-11-28 10:29:43.6858 Debug 01-04-01-13-01-E8 2015-11-28 10:29:43.6868 Debug 06 2015-11-28 10:29:43.6872 Trace SendDataReady 2015-11-28 10:29:43.6881 Debug 01-07-00-13-26-00-00-02 2015-11-28 10:29:43.6881 Warn Expected message length is 9 currently received length is 8 2015-11-28 10:29:43.6890 Debug CF 2015-11-28 10:29:43.6890 Info Merged buffer to message: 01-07-00-13-26-00-00-02-CF 2015-11-28 10:29:43.6902 Debug 06 2015-11-28 10:29:43.6902 Trace Complete 2015-11-28 10:29:43.6914 Trace [[[ END REQUEST ]]] took 112 ms 2015-11-28 10:29:43.6924 Debug 01-09-00-04-00-05-03-20-03-00-D7 2015-11-28 10:29:43.6935 Debug 06 2015-11-28 10:29:43.6940 Debug 5 Level 0 "2015-11-28 10:29:43.6983 Info HomeAutomation.ZWave 5 ZWave Node Status.Level 0" "2015-11-28 10:29:43.7036 Info HomeAutomation.ZWave 5 ZWave Node ZWaveNode.Basic 0" Node 2, motion detected (ERROR: shown as luminance in Homegenie, tamper alarm should not be present as this sensor does not support?) ------------------------------------------------------------------------------------------------------------------------------------- 2015-11-28 10:33:22.3074 Debug 01-09-00-04-00-02-03-20-01-FF-2D 2015-11-28 10:33:22.3083 Debug 06 2015-11-28 10:33:22.3083 Debug 2 Level 255 "2015-11-28 10:33:22.3117 Info HomeAutomation.ZWave 2 ZWave Node Status.Level 255" "2015-11-28 10:33:22.3141 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Basic 255" 2015-11-28 10:33:22.6146 Debug 01-11-00-04-00-02-0B-71-05-00-00-00-FF-07-07-01-07-00-6E 2015-11-28 10:33:22.6155 Debug 06 2015-11-28 10:33:22.6404 Debug 2 AlarmTampered 7 "2015-11-28 10:33:22.6542 Info HomeAutomation.ZWave 2 ZWave Node Sensor.Tamper 7" "2015-11-28 10:33:23.7225 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941160 [OPEN]" Node 2, get manufacturer specific info -------------------------------------- 2015-11-28 10:48:43.1747 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:48:43.1752 Trace WaitAck 2015-11-28 10:48:43.1758 Trace Sending Message (Node=2 CallbackId=2C Function=SendData CommandClass=ManufacturerSpecific) 2015-11-28 10:48:43.1758 Debug 01-09-00-13-02-02-72-04-05-2C-BA 2015-11-28 10:48:43.1935 Debug 06-01-04-01-13-01-E8 2015-11-28 10:48:43.1945 Debug 06 2015-11-28 10:48:43.1948 Trace SendDataReady 2015-11-28 10:48:43.2013 Debug 01-07-00-13-2C-00-00-02-C5 2015-11-28 10:48:43.2025 Debug 06 2015-11-28 10:48:43.2033 Trace Complete 2015-11-28 10:48:43.2044 Trace [[[ END REQUEST ]]] took 29 ms 2015-11-28 10:48:43.3056 Debug 01-0E-00-04-00-02-08-72-05-00-86-00-02-00-4A-46 2015-11-28 10:48:43.3069 Debug 06 2015-11-28 10:48:43.3069 Debug 2 ManufacturerSpecific ZWaveLib.CommandClasses.ManufacturerSpecificInfo "2015-11-28 10:48:43.3100 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.ManufacturerSpecific 0086:0002:004A" "2015-11-28 10:48:43.4662 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/ManufacturerSpecific.Get/?_=1448699941290 [CLOSED AFTER 0.373 seconds]" Node 2, read parameter but device is asleep ------------------------------------------- 2015-11-28 10:48:43.8055 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:48:43.8060 Trace WaitAck 2015-11-28 10:48:43.8060 Trace Sending Message (Node=2 2015-11-28 10:48:43.8072 Debug 01-04-00-60-02-99 2015-11-28 10:48:43.8107 Debug 06 2015-11-28 10:48:43.9114 Debug 01-04-01-60-01-9B 2015-11-28 10:48:43.9124 Debug 06 2015-11-28 10:48:43.9129 Trace SendDataReady 2015-11-28 10:48:43.9152 Debug 01-1A-00-49-84-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A 2015-11-28 10:48:43.9160 Warn Expected message length is 28 2015-11-28 10:48:43.9168 Debug EF-5A-82-E8 2015-11-28 10:48:43.9168 Info Merged buffer to message: 01-1A-00-49-84-02-14-04-07-01-86-72-5E-59-85-73-71-84-80-30-31-70-98-7A-EF-5A-82-E8 2015-11-28 10:48:43.9185 Debug 06 In sendMsg - SecurityHandler In sendRequestNonce - SecurityHandler In sendRequestNonce - not waiting for Nonce - SecurityHandler "2015-11-28 10:48:44.7189 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941292 [CLOSED AFTER 0.007 seconds]" "2015-11-28 10:48:44.7107 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941292 [OPEN]" "2015-11-28 10:48:53.5150 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/NodeInfo.Get/?_=1448699941291 [CLOSED AFTER 10.035 seconds]" "2015-11-28 10:48:53.5555 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1448699941293 [OPEN]" 2015-11-28 10:48:53.8073 Trace Error 2015-11-28 10:48:53.8083 Warn Message timeout (Node=2 2015-11-28 10:48:53.8090 Debug 2 Timeout 2015-11-28 10:48:53.8234 Trace [[[ END REQUEST ]]] took 10017 ms 2015-11-28 10:48:53.8242 Warn Could not deliver message to Node 2 (CallbackId=00 "2015-11-28 10:48:53.8251 Info HomeAutomation.ZWave 1 Z-Wave Controller Controller.Status Node 2 response timeout!" 2015-11-28 10:48:53.9257 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:48:53.9261 Trace WaitAck 2015-11-28 10:48:53.9261 Trace Sending Message (Node=2 2015-11-28 10:48:53.9273 Debug 01-04-00-60-02-99 2015-11-28 10:48:54.0033 Debug 06-01-04-01-60-01-9B 2015-11-28 10:48:54.0043 Debug 06 2015-11-28 10:48:54.0043 Trace SendDataReady "2015-11-28 10:48:54.7170 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941294 [OPEN]" "2015-11-28 10:48:54.7346 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941294 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:48:56.6873 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1448699941293 [CLOSED AFTER 3.132 seconds]" "2015-11-28 10:48:56.7245 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /hg/html/ext/zwave/pepper1db/0086_0002_004A.json [OPEN]" "2015-11-28 10:48:56.7393 Info WebServiceGateway 192.168.1.103 HTTP GET 304 /hg/html/ext/zwave/pepper1db/0086_0002_004A.json [CLOSED AFTER 0.015 seconds]" 2015-11-28 10:48:58.2137 Debug 01-06-00-49-81-00-00-31 2015-11-28 10:48:58.2149 Debug 06 2015-11-28 10:48:58.2159 Trace Error 2015-11-28 10:48:58.2169 Trace [[[ END REQUEST ]]] took 4290 ms 2015-11-28 10:48:58.2169 Warn Could not deliver message to Node 2 (CallbackId=00 2015-11-28 10:48:58.3184 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:48:58.3188 Trace WaitAck 2015-11-28 10:48:58.3188 Trace Sending Message (Node=2 2015-11-28 10:48:58.3206 Debug 01-04-00-60-02-99 2015-11-28 10:48:58.4216 Debug 06-01-04-01-60-01-9B 2015-11-28 10:48:58.4231 Debug 06 2015-11-28 10:48:58.4231 Trace SendDataReady 2015-11-28 10:49:02.5325 Debug 01-06-00-49-81-00-00-31 2015-11-28 10:49:02.5335 Debug 06 2015-11-28 10:49:02.5339 Trace Error 2015-11-28 10:49:02.5351 Trace [[[ END REQUEST ]]] took 4216 ms 2015-11-28 10:49:02.5351 Warn Delivery of message to Node 2 failed (CallbackId=00). 2015-11-28 10:49:02.5360 Debug 2 Error 2015-11-28 10:49:02.5392 Warn Node is flagged as sleeping "2015-11-28 10:49:02.5403 Info HomeAutomation.ZWave 2 Z-Wave Node Status.Error Response timeout!" 2015-11-28 10:49:02.6403 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:02.6403 Trace WaitAck 2015-11-28 10:49:02.6412 Trace Sending Message (Node=2 2015-11-28 10:49:02.6420 Debug 01-09-00-13-02-02-98-40-05-2D-15 2015-11-28 10:49:02.7390 Debug 06-01-04-01-13-01-E8 2015-11-28 10:49:02.7399 Debug 06 2015-11-28 10:49:02.7399 Trace SendDataReady 2015-11-28 10:49:02.8418 Debug 01-07-00-13-2D-01-00-0B-CC 2015-11-28 10:49:02.8429 Debug 06 2015-11-28 10:49:02.8439 Trace Error 2015-11-28 10:49:02.8450 Trace [[[ END REQUEST ]]] took 204 ms 2015-11-28 10:49:02.8450 Warn Could not deliver message to Node 2 (CallbackId=2D 2015-11-28 10:49:02.9461 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:02.9461 Trace WaitAck 2015-11-28 10:49:02.9471 Trace Sending Message (Node=2 2015-11-28 10:49:02.9471 Debug 01-09-00-13-02-02-98-40-05-2D-15 2015-11-28 10:49:03.0484 Debug 06-01-04-01-13-01-E8 2015-11-28 10:49:03.0503 Debug 06 2015-11-28 10:49:03.0508 Trace SendDataReady 2015-11-28 10:49:03.0508 Debug 01-07-00-13-2D-01-00-0B 2015-11-28 10:49:03.0524 Warn Expected message length is 9 2015-11-28 10:49:03.0534 Debug CC 2015-11-28 10:49:03.0547 Info Merged buffer to message: 01-07-00-13-2D-01-00-0B-CC 2015-11-28 10:49:03.0559 Debug 06 2015-11-28 10:49:03.0559 Trace Error 2015-11-28 10:49:03.0576 Trace [[[ END REQUEST ]]] took 110 ms 2015-11-28 10:49:03.0582 Warn Could not deliver message to Node 2 (CallbackId=2D 2015-11-28 10:49:03.1588 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:03.1593 Trace WaitAck 2015-11-28 10:49:03.1593 Trace Sending Message (Node=2 2015-11-28 10:49:03.1604 Debug 01-09-00-13-02-02-98-40-05-2D-15 2015-11-28 10:49:03.2615 Debug 06-01-04-01-13-01-E8 2015-11-28 10:49:03.2631 Debug 06 2015-11-28 10:49:03.2631 Trace SendDataReady 2015-11-28 10:49:03.3648 Debug 01-07-00-13-2D-01-00-0D-CA 2015-11-28 10:49:03.3660 Debug 06 2015-11-28 10:49:03.3660 Trace Error 2015-11-28 10:49:03.3677 Trace [[[ END REQUEST ]]] took 208 ms 2015-11-28 10:49:03.3682 Warn Delivery of message to Node 2 failed (CallbackId=2D). 2015-11-28 10:49:03.3682 Debug 2 Error 2015-11-28 10:49:03.3709 Warn Node is flagged as sleeping 2015-11-28 10:49:03.3718 Trace Adding message 01-09-00-13-02-02-98-40-05-2D-15 2015-11-28 10:49:03.3718 Debug 2 WakeUpSleepingStatus 1 "2015-11-28 10:49:03.3741 Info HomeAutomation.ZWave 2 Z-Wave Node Status.Error Response timeout!" "2015-11-28 10:49:03.3760 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 1" "2015-11-28 10:49:04.7203 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941295 [CLOSED AFTER 0.006 seconds]" Node 2, various parameter gets. Looks like the device goes to sleep after every get. Hence, have to wakeup first before get (ERROR: Homegenie now shows parameters correctly except for tamper = 7???) --------------------------------------------------------------------------------------------------------------------------- "2015-11-28 10:49:54.7436 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941301 [OPEN]" 2015-11-28 10:49:56.4843 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:49:56.4853 Debug 06 2015-11-28 10:49:56.4862 Debug 2 WakeUpSleepingStatus 0 2015-11-28 10:49:56.4903 Trace Sending message 0 01-09-00-13-02-02-98-40-05-2D-15 "2015-11-28 10:49:56.4912 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 0" 2015-11-28 10:49:56.4938 Trace Sending message 1 01-0A-00-13-02-03-85-02-01-05-2E-4A 2015-11-28 10:49:56.4952 Debug 2 WakeUpNotify 1 "2015-11-28 10:49:56.5158 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:49:56.9674 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:56.9678 Trace WaitAck 2015-11-28 10:49:56.9678 Trace Sending Message (Node=2 CallbackId=31 Function=SendData CommandClass=Security) 2015-11-28 10:49:56.9695 Debug 01-09-00-13-02-02-98-40-05-31-09 2015-11-28 10:49:57.0166 Debug 06-01-04-01-13-01-E8 2015-11-28 10:49:57.0177 Debug 06 2015-11-28 10:49:57.0181 Trace SendDataReady 2015-11-28 10:49:57.0189 Debug 01-07-00-13-31-00-00-01 2015-11-28 10:49:57.0189 Warn Expected message length is 9 currently received length is 8 2015-11-28 10:49:57.0198 Debug DB 2015-11-28 10:49:57.0198 Info Merged buffer to message: 01-07-00-13-31-00-00-01-DB 2015-11-28 10:49:57.0235 Debug 06 2015-11-28 10:49:57.0240 Trace Complete 2015-11-28 10:49:57.0311 Trace [[[ END REQUEST ]]] took 63 ms 2015-11-28 10:49:57.1318 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:57.1322 Trace WaitAck 2015-11-28 10:49:57.1331 Trace Sending Message (Node=2 CallbackId=32 Function=SendData CommandClass=Association) 2015-11-28 10:49:57.1339 Debug 01-0A-00-13-02-03-85-02-01-05-32-56 2015-11-28 10:49:57.2332 Debug 06-01-04-01-13-01-E8 2015-11-28 10:49:57.2341 Debug 06 2015-11-28 10:49:57.2341 Trace SendDataReady 2015-11-28 10:49:57.3354 Debug 01-07-00-13-32-00-00-02-DB 2015-11-28 10:49:57.3384 Debug 06 2015-11-28 10:49:57.3391 Trace Complete 2015-11-28 10:49:57.3403 Trace [[[ END REQUEST ]]] took 208 ms 2015-11-28 10:49:57.3412 Debug 01-0C-00-04-00-02-06-85-03-01-05-00-01-70 2015-11-28 10:49:57.3423 Debug 06 2015-11-28 10:49:57.3432 Debug 2 Association ZWaveLib.CommandClasses.Association+AssociationResponse "2015-11-28 10:49:57.3458 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.Max 5" "2015-11-28 10:49:57.3482 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.Count 0" "2015-11-28 10:49:57.3649 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.1 1" 2015-11-28 10:49:57.5147 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:49:57.5151 Trace WaitAck 2015-11-28 10:49:57.5151 Trace Sending Message (Node=2 CallbackId=33 Function=SendData CommandClass=Battery) 2015-11-28 10:49:57.5162 Debug 01-09-00-13-02-02-80-02-05-33-51 2015-11-28 10:49:57.5209 Debug 06 "2015-11-28 10:49:57.6214 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941302 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:49:57.6156 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941302 [OPEN]" 2015-11-28 10:49:57.6233 Debug 01-04-01-13-01-E8 2015-11-28 10:49:57.6254 Debug 06 2015-11-28 10:49:57.6254 Trace SendDataReady "2015-11-28 10:49:56.9972 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:49:57.7265 Debug 01-07-00-13-33-00-00-02-DA 2015-11-28 10:49:57.7274 Debug 06 2015-11-28 10:49:57.7278 Trace Complete 2015-11-28 10:49:57.7290 Trace [[[ END REQUEST ]]] took 213 ms 2015-11-28 10:49:58.2308 Debug 01-09-00-04-00-02-03-80-03-51-21 2015-11-28 10:49:58.2317 Debug 06 2015-11-28 10:49:58.2322 Debug 2 Battery 81 "2015-11-28 10:49:58.2352 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 81" "2015-11-28 10:49:58.2373 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 81" "2015-11-28 10:50:04.7168 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941303 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:50:04.7112 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941303 [OPEN]" 2015-11-28 10:50:04.8334 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:04.8340 Trace WaitAck 2015-11-28 10:50:04.8340 Trace Sending Message (Node=2 CallbackId=34 Function=SendData CommandClass=Association) 2015-11-28 10:50:04.8351 Debug 01-0A-00-13-02-03-85-02-01-05-34-50 2015-11-28 10:50:04.8515 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:04.8524 Debug 06 2015-11-28 10:50:04.8533 Trace SendDataReady 2015-11-28 10:50:04.9541 Debug 01-07-00-13-34-00-00-02-DD 2015-11-28 10:50:04.9551 Debug 06 2015-11-28 10:50:04.9559 Trace Complete 2015-11-28 10:50:04.9568 Trace [[[ END REQUEST ]]] took 122 ms 2015-11-28 10:50:04.9580 Debug 01-0C-00-04-00-02-06-85-03-01-05-00-01-70 2015-11-28 10:50:04.9591 Debug 06 2015-11-28 10:50:04.9591 Debug 2 Association ZWaveLib.CommandClasses.Association+AssociationResponse "2015-11-28 10:50:04.9622 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.Max 5" "2015-11-28 10:50:04.9647 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.Count 0" "2015-11-28 10:50:04.9752 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Associations.1 1" "2015-11-28 10:50:04.8041 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Association.Get/1/?_=1448699941304 [OPEN]" "2015-11-28 10:50:05.1443 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Association.Get/1/?_=1448699941304 [CLOSED AFTER 0.34 seconds]" "2015-11-28 10:50:14.7204 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941305 [OPEN]" "2015-11-28 10:50:14.7850 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941305 [CLOSED AFTER 0.065 seconds]" 2015-11-28 10:50:22.6138 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:22.6138 Trace WaitAck 2015-11-28 10:50:22.6138 Trace Sending Message (Node=2 CallbackId=35 Function=SendData CommandClass=Configuration) 2015-11-28 10:50:22.6153 Debug 01-0A-00-13-02-03-70-05-01-05-35-A3 2015-11-28 10:50:22.6522 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:22.6532 Debug 06 2015-11-28 10:50:22.6532 Trace SendDataReady "2015-11-28 10:50:22.4199 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941306 [OPEN]" 2015-11-28 10:50:22.8545 Debug 01-07-00-13-35-01-00-15-CA 2015-11-28 10:50:22.8554 Debug 06 2015-11-28 10:50:22.8554 Trace Error 2015-11-28 10:50:22.8565 Trace [[[ END REQUEST ]]] took 242 ms 2015-11-28 10:50:22.8571 Warn Could not deliver message to Node 2 (CallbackId=35 Retry=1) 2015-11-28 10:50:22.9576 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:22.9580 Trace WaitAck 2015-11-28 10:50:22.9580 Trace Sending Message (Node=2 CallbackId=35 Function=SendData CommandClass=Configuration) 2015-11-28 10:50:22.9592 Debug 01-0A-00-13-02-03-70-05-01-05-35-A3 2015-11-28 10:50:23.0600 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:23.0610 Debug 06 2015-11-28 10:50:23.0610 Trace SendDataReady 2015-11-28 10:50:23.1622 Debug 01-07-00-13-35-01-00-10-CF 2015-11-28 10:50:23.1632 Debug 06 2015-11-28 10:50:23.1632 Trace Error 2015-11-28 10:50:23.1645 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:50:23.1649 Warn Could not deliver message to Node 2 (CallbackId=35 Retry=2) 2015-11-28 10:50:23.2655 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:23.2659 Trace WaitAck 2015-11-28 10:50:23.2659 Trace Sending Message (Node=2 CallbackId=35 Function=SendData CommandClass=Configuration) 2015-11-28 10:50:23.2670 Debug 01-0A-00-13-02-03-70-05-01-05-35-A3 2015-11-28 10:50:23.3677 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:23.3690 Debug 06 2015-11-28 10:50:23.3690 Trace SendDataReady 2015-11-28 10:50:23.4701 Debug 01-07-00-13-35-01-00-0C-D3 2015-11-28 10:50:23.4712 Debug 06 2015-11-28 10:50:23.4712 Trace Error 2015-11-28 10:50:23.4723 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:50:23.4730 Warn Delivery of message to Node 2 failed (CallbackId=35). 2015-11-28 10:50:23.4730 Debug 2 Error 2015-11-28 10:50:23.4751 Warn Node is flagged as sleeping message will be re-sent on Wake Up (Node=2 CallbackId=2 Function=35 CommandClass=SendData) 2015-11-28 10:50:23.4751 Trace Adding message 01-0A-00-13-02-03-70-05-01-05-35-A3 2015-11-28 10:50:23.4762 Debug 2 WakeUpSleepingStatus 1 "2015-11-28 10:50:23.4775 Info HomeAutomation.ZWave 2 Z-Wave Node Status.Error Response timeout!" "2015-11-28 10:50:23.4791 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 1" "2015-11-28 10:50:23.8532 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.KwLoad 0.000" "2015-11-28 10:50:23.8552 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.WattLoad 0.000" "2015-11-28 10:50:23.8614 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingDevices 0" "2015-11-28 10:50:23.8727 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingLights 0" "2015-11-28 10:50:23.8896 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingSwitches 0" "2015-11-28 10:50:24.7314 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941307 [CLOSED AFTER 0.01 seconds]" "2015-11-28 10:50:24.7219 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941307 [OPEN]" "2015-11-28 10:50:32.4549 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941306 [CLOSED AFTER 10.035 seconds]" "2015-11-28 10:50:34.7789 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941308 [CLOSED AFTER 0.063 seconds]" "2015-11-28 10:50:34.7141 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941308 [OPEN]" 2015-11-28 10:50:38.6448 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:50:38.6457 Debug 06 2015-11-28 10:50:38.6463 Debug 2 WakeUpSleepingStatus 0 2015-11-28 10:50:38.6483 Trace Sending message 0 01-0A-00-13-02-03-70-05-01-05-35-A3 2015-11-28 10:50:38.6503 Debug 2 WakeUpNotify 1 "2015-11-28 10:50:38.6510 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 0" "2015-11-28 10:50:38.6658 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:50:39.1269 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:39.1269 Trace WaitAck 2015-11-28 10:50:39.1269 Trace Sending Message (Node=2 CallbackId=36 Function=SendData CommandClass=Configuration) 2015-11-28 10:50:39.1284 Debug 01-0A-00-13-02-03-70-05-01-05-36-A0 2015-11-28 10:50:39.1672 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:39.1680 Debug 06 2015-11-28 10:50:39.1680 Trace SendDataReady "2015-11-28 10:50:38.7434 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:50:39.2691 Debug 01-07-00-13-36-00-00-02-DF 2015-11-28 10:50:39.2702 Debug 06 2015-11-28 10:50:39.2702 Trace Complete 2015-11-28 10:50:39.2714 Trace [[[ END REQUEST ]]] took 144 ms 2015-11-28 10:50:39.3720 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:39.3720 Trace WaitAck 2015-11-28 10:50:39.3731 Trace Sending Message (Node=2 CallbackId=37 Function=SendData CommandClass=Battery) 2015-11-28 10:50:39.3731 Debug 01-09-00-13-02-02-80-02-05-37-55 2015-11-28 10:50:39.4746 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:39.4757 Debug 06 2015-11-28 10:50:39.4761 Trace SendDataReady 2015-11-28 10:50:39.5770 Debug 01-07-00-13-37-00-00-02-DE 2015-11-28 10:50:39.5780 Debug 06 2015-11-28 10:50:39.5780 Trace Complete 2015-11-28 10:50:39.5792 Trace [[[ END REQUEST ]]] took 206 ms "2015-11-28 10:50:39.7201 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941309 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:50:39.7143 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941309 [OPEN]" 2015-11-28 10:50:40.0811 Debug 01-09-00-04-00-02-03-80-03-50-20 2015-11-28 10:50:40.0820 Debug 06 2015-11-28 10:50:40.0820 Debug 2 Battery 80 "2015-11-28 10:50:40.0861 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 80" "2015-11-28 10:50:40.0883 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 80" "2015-11-28 10:50:40.5006 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /hg/html/pages/control/widgets/homegenie/generic/images/battery_level_80.png [OPEN]" "2015-11-28 10:50:40.5067 Info WebServiceGateway 192.168.1.103 HTTP GET 304 /hg/html/pages/control/widgets/homegenie/generic/images/battery_level_80.png [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:50:44.6246 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941310 [OPEN]" 2015-11-28 10:50:44.6838 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:50:44.6842 Trace WaitAck 2015-11-28 10:50:44.6856 Trace Sending Message (Node=2 CallbackId=38 Function=SendData CommandClass=Configuration) 2015-11-28 10:50:44.6869 Debug 01-0A-00-13-02-03-70-05-01-05-38-AE 2015-11-28 10:50:44.6966 Debug 06-01-04-01-13-01-E8 2015-11-28 10:50:44.6976 Debug 06 2015-11-28 10:50:44.6979 Trace SendDataReady "2015-11-28 10:50:44.7218 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941311 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:50:44.7139 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941311 [OPEN]" 2015-11-28 10:50:44.7987 Debug 01-07-00-13-38-00-00-02-D1 2015-11-28 10:50:44.7997 Debug 06 2015-11-28 10:50:44.8001 Trace Complete 2015-11-28 10:50:44.8008 Trace [[[ END REQUEST ]]] took 116 ms "2015-11-28 10:50:54.6588 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941310 [CLOSED AFTER 10.034 seconds]" "2015-11-28 10:50:54.7130 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941312 [OPEN]" "2015-11-28 10:50:54.7753 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941312 [CLOSED AFTER 0.062 seconds]" 2015-11-28 10:51:03.9498 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:03.9503 Trace WaitAck 2015-11-28 10:51:03.9503 Trace Sending Message (Node=2 CallbackId=39 Function=SendData CommandClass=WakeUp) 2015-11-28 10:51:03.9514 Debug 01-09-00-13-02-02-84-05-05-39-58 2015-11-28 10:51:03.9768 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:03.9778 Debug 06 2015-11-28 10:51:03.9782 Trace SendDataReady "2015-11-28 10:51:03.9204 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/WakeUp.Get/?_=1448699941313 [OPEN]" 2015-11-28 10:51:04.2792 Debug 01-07-00-13-39-01-00-1D-CE 2015-11-28 10:51:04.2801 Debug 06 2015-11-28 10:51:04.2801 Trace Error 2015-11-28 10:51:04.2814 Trace [[[ END REQUEST ]]] took 331 ms 2015-11-28 10:51:04.2819 Warn Could not deliver message to Node 2 (CallbackId=39 Retry=1) 2015-11-28 10:51:04.3824 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:04.3829 Trace WaitAck 2015-11-28 10:51:04.3829 Trace Sending Message (Node=2 CallbackId=39 Function=SendData CommandClass=WakeUp) 2015-11-28 10:51:04.3841 Debug 01-09-00-13-02-02-84-05-05-39-58 2015-11-28 10:51:04.4849 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:04.4859 Debug 06 2015-11-28 10:51:04.4859 Trace SendDataReady 2015-11-28 10:51:04.5872 Debug 01-07-00-13-39-01-00-0A-D9 2015-11-28 10:51:04.5882 Debug 06 2015-11-28 10:51:04.5882 Trace Error 2015-11-28 10:51:04.5894 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:51:04.5894 Warn Could not deliver message to Node 2 (CallbackId=39 Retry=2) 2015-11-28 10:51:04.6904 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:04.6908 Trace WaitAck 2015-11-28 10:51:04.6908 Trace Sending Message (Node=2 CallbackId=39 Function=SendData CommandClass=WakeUp) 2015-11-28 10:51:04.6920 Debug 01-09-00-13-02-02-84-05-05-39-58 "2015-11-28 10:51:04.7774 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941314 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:51:04.7695 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941314 [OPEN]" 2015-11-28 10:51:04.7927 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:04.7938 Debug 06 2015-11-28 10:51:04.7938 Trace SendDataReady 2015-11-28 10:51:04.8950 Debug 01-07-00-13-39-01-00-07-D4 2015-11-28 10:51:04.8961 Debug 06 2015-11-28 10:51:04.8961 Trace Error 2015-11-28 10:51:04.8977 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:51:04.8982 Warn Delivery of message to Node 2 failed (CallbackId=39). 2015-11-28 10:51:04.8982 Debug 2 Error 2015-11-28 10:51:04.9007 Warn Node is flagged as sleeping message will be re-sent on Wake Up (Node=2 CallbackId=2 Function=39 CommandClass=SendData) 2015-11-28 10:51:04.9014 Trace Adding message 01-09-00-13-02-02-84-05-05-39-58 2015-11-28 10:51:04.9014 Debug 2 WakeUpSleepingStatus 1 "2015-11-28 10:51:04.9035 Info HomeAutomation.ZWave 2 Z-Wave Node Status.Error Response timeout!" "2015-11-28 10:51:04.9056 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 1" "2015-11-28 10:51:13.9590 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/WakeUp.Get/?_=1448699941313 [CLOSED AFTER 10.039 seconds]" "2015-11-28 10:51:14.7148 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941315 [OPEN]" "2015-11-28 10:51:14.7740 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941315 [CLOSED AFTER 0.057 seconds]" 2015-11-28 10:51:18.2625 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:51:18.2635 Debug 06 2015-11-28 10:51:18.2651 Debug 2 WakeUpSleepingStatus 0 2015-11-28 10:51:18.2671 Trace Sending message 0 01-09-00-13-02-02-84-05-05-39-58 2015-11-28 10:51:18.2681 Debug 2 WakeUpNotify 1 "2015-11-28 10:51:18.2697 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 0" "2015-11-28 10:51:18.2733 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:51:18.5498 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:18.5502 Trace WaitAck 2015-11-28 10:51:18.5502 Trace Sending Message (Node=2 CallbackId=3A Function=SendData CommandClass=WakeUp) 2015-11-28 10:51:18.5513 Debug 01-09-00-13-02-02-84-05-05-3A-5B 2015-11-28 10:51:18.5738 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:18.5746 Debug 06 2015-11-28 10:51:18.5751 Trace SendDataReady 2015-11-28 10:51:18.6761 Debug 01-07-00-13-3A-00-00-01-D0 2015-11-28 10:51:18.6770 Debug 06 2015-11-28 10:51:18.6770 Trace Complete 2015-11-28 10:51:18.6783 Trace [[[ END REQUEST ]]] took 128 ms 2015-11-28 10:51:18.6791 Debug 01-0C-00-04-00-02-06-84-06-00-02-D0-01-A2 2015-11-28 10:51:18.6801 Debug 06 2015-11-28 10:51:18.6808 Debug 2 WakeUpInterval 720 "2015-11-28 10:51:18.6888 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpInterval 720" 2015-11-28 10:51:18.7791 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:18.7791 Trace WaitAck 2015-11-28 10:51:18.7801 Trace Sending Message (Node=2 CallbackId=3B Function=SendData CommandClass=Battery) 2015-11-28 10:51:18.7811 Debug 01-09-00-13-02-02-80-02-05-3B-59 2015-11-28 10:51:18.7889 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:18.7889 Debug 06 2015-11-28 10:51:18.7901 Trace SendDataReady "2015-11-28 10:51:18.3650 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:51:18.8909 Debug 01-07-00-13-3B-00-00-01-D1 2015-11-28 10:51:18.8927 Debug 06 2015-11-28 10:51:18.8935 Trace Complete 2015-11-28 10:51:18.8944 Trace [[[ END REQUEST ]]] took 114 ms "2015-11-28 10:51:18.9345 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941316 [CLOSED AFTER 0.012 seconds]" "2015-11-28 10:51:18.9207 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941316 [OPEN]" 2015-11-28 10:51:19.5966 Debug 01-09-00-04-00-02-03-80-03-50-20 2015-11-28 10:51:19.5979 Debug 06 2015-11-28 10:51:19.5979 Debug 2 Battery 80 "2015-11-28 10:51:19.6015 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 80" "2015-11-28 10:51:19.6039 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 80" "2015-11-28 10:51:24.0521 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.KwLoad 0.000" "2015-11-28 10:51:24.0541 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.WattLoad 0.000" "2015-11-28 10:51:24.0640 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingDevices 0" "2015-11-28 10:51:24.0787 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingLights 0" "2015-11-28 10:51:24.0920 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingSwitches 0" "2015-11-28 10:51:24.5354 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/WakeUp.Get/?_=1448699941317 [OPEN]" "2015-11-28 10:51:24.7436 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941318 [CLOSED AFTER 0.028 seconds]" "2015-11-28 10:51:24.7152 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941318 [OPEN]" "2015-11-28 10:51:25.2340 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/WakeUp.Get/?_=1448699941317 [CLOSED AFTER 0.699 seconds]" 2015-11-28 10:51:24.9970 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:24.9970 Trace WaitAck 2015-11-28 10:51:24.9979 Trace Sending Message (Node=2 CallbackId=3C Function=SendData CommandClass=WakeUp) 2015-11-28 10:51:24.9979 Debug 01-09-00-13-02-02-84-05-05-3C-5D 2015-11-28 10:51:25.0136 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:25.0144 Debug 06 2015-11-28 10:51:25.0144 Trace SendDataReady 2015-11-28 10:51:25.1155 Debug 01-07-00-13-3C-00-00-02-D5 2015-11-28 10:51:25.1166 Debug 06 2015-11-28 10:51:25.1171 Trace Complete 2015-11-28 10:51:25.1179 Trace [[[ END REQUEST ]]] took 120 ms 2015-11-28 10:51:25.1179 Debug 01-0C-00-04-00-02-06-84-06-00-02-D0-01-A2 2015-11-28 10:51:25.1197 Debug 06 2015-11-28 10:51:25.1202 Debug 2 WakeUpInterval 720 "2015-11-28 10:51:25.1222 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpInterval 720" 2015-11-28 10:51:32.2611 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:32.2611 Trace WaitAck 2015-11-28 10:51:32.2620 Trace Sending Message (Node=2 CallbackId=3D Function=SendData CommandClass=Configuration) 2015-11-28 10:51:32.2620 Debug 01-0A-00-13-02-03-70-05-01-05-3D-AB 2015-11-28 10:51:32.2739 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:32.2739 Debug 06 2015-11-28 10:51:32.2750 Trace SendDataReady "2015-11-28 10:51:31.9141 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941319 [OPEN]" 2015-11-28 10:51:32.3758 Debug 01-07-00-13-3D-00-00-02-D4 2015-11-28 10:51:32.3767 Debug 06 2015-11-28 10:51:32.3773 Trace Complete 2015-11-28 10:51:32.3782 Trace [[[ END REQUEST ]]] took 116 ms "2015-11-28 10:51:34.7232 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941320 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:51:34.7153 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941320 [OPEN]" "2015-11-28 10:51:42.0068 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/1/?_=1448699941319 [CLOSED AFTER 10.093 seconds]" "2015-11-28 10:51:44.7246 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941321 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:51:44.7184 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941321 [OPEN]" 2015-11-28 10:51:49.9120 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:51:49.9132 Debug 06 2015-11-28 10:51:49.9132 Debug 2 WakeUpNotify 1 "2015-11-28 10:51:49.9163 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" 2015-11-28 10:51:49.9842 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:49.9842 Trace WaitAck 2015-11-28 10:51:49.9851 Trace Sending Message (Node=2 CallbackId=3E Function=SendData CommandClass=Battery) 2015-11-28 10:51:49.9851 Debug 01-09-00-13-02-02-80-02-05-3E-5C 2015-11-28 10:51:50.0164 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:50.0173 Debug 06 2015-11-28 10:51:50.0173 Trace SendDataReady 2015-11-28 10:51:50.0189 Debug 01-07-00-13-3E-00-00-02-D7 2015-11-28 10:51:50.0200 Debug 06 2015-11-28 10:51:50.0200 Trace Complete 2015-11-28 10:51:50.0243 Trace [[[ END REQUEST ]]] took 39 ms "2015-11-28 10:51:49.9720 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:51:50.7263 Debug 01-09-00-04-00-02-03-80-03-4F-3F 2015-11-28 10:51:50.7274 Debug 06 2015-11-28 10:51:50.7279 Debug 2 Battery 79 "2015-11-28 10:51:50.7307 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 79" "2015-11-28 10:51:50.7336 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 79" "2015-11-28 10:51:50.9044 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941322 [CLOSED AFTER 0.014 seconds]" "2015-11-28 10:51:50.8908 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941322 [OPEN]" "2015-11-28 10:51:50.9224 Info WebServiceGateway 192.168.1.103 HTTP GET 304 /hg/html/pages/control/widgets/homegenie/generic/images/unknown.png [CLOSED AFTER 0.005 seconds]" "2015-11-28 10:51:50.9171 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /hg/html/pages/control/widgets/homegenie/generic/images/unknown.png [OPEN]" "2015-11-28 10:51:54.7173 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941323 [OPEN]" "2015-11-28 10:51:54.7847 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941323 [CLOSED AFTER 0.067 seconds]" 2015-11-28 10:51:55.1705 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:51:55.1709 Trace WaitAck 2015-11-28 10:51:55.1709 Trace Sending Message (Node=2 CallbackId=3F Function=SendData CommandClass=Configuration) 2015-11-28 10:51:55.1720 Debug 01-0A-00-13-02-03-70-05-65-05-3F-CD 2015-11-28 10:51:55.1846 Debug 06-01-04-01-13-01-E8 2015-11-28 10:51:55.1854 Debug 06 2015-11-28 10:51:55.1858 Trace SendDataReady 2015-11-28 10:51:55.2865 Debug 01-07-00-13-3F-00-00-02-D6 2015-11-28 10:51:55.2876 Debug 06 2015-11-28 10:51:55.2880 Trace Complete 2015-11-28 10:51:55.2893 Trace [[[ END REQUEST ]]] took 118 ms 2015-11-28 10:51:55.2901 Debug 01-0E-00-04-00-02-08-70-06-65-04-00-00-00-E1-09 2015-11-28 10:51:55.2910 Debug 06 2015-11-28 10:51:55.2910 Debug 2 Configuration 225 "2015-11-28 10:51:55.2936 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Variables.101 225" "2015-11-28 10:51:54.9623 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/101/?_=1448699941324 [OPEN]" "2015-11-28 10:51:55.4298 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/101/?_=1448699941324 [CLOSED AFTER 0.466 seconds]" "2015-11-28 10:52:04.7190 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941325 [OPEN]" "2015-11-28 10:52:04.7250 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941325 [CLOSED AFTER 0.006 seconds]" "2015-11-28 10:52:07.0600 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/111/?_=1448699941326 [OPEN]" 2015-11-28 10:52:07.3935 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:07.3940 Trace WaitAck 2015-11-28 10:52:07.3940 Trace Sending Message (Node=2 CallbackId=40 Function=SendData CommandClass=Configuration) 2015-11-28 10:52:07.3951 Debug 01-0A-00-13-02-03-70-05-6F-05-40-B8 2015-11-28 10:52:07.4153 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:07.4162 Debug 06 2015-11-28 10:52:07.4162 Trace SendDataReady 2015-11-28 10:52:07.7177 Debug 01-07-00-13-40-01-00-16-BC 2015-11-28 10:52:07.7187 Debug 06 2015-11-28 10:52:07.7191 Trace Error 2015-11-28 10:52:07.7198 Trace [[[ END REQUEST ]]] took 325 ms 2015-11-28 10:52:07.7198 Warn Could not deliver message to Node 2 (CallbackId=40 Retry=1) 2015-11-28 10:52:07.8208 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:07.8212 Trace WaitAck 2015-11-28 10:52:07.8212 Trace Sending Message (Node=2 CallbackId=40 Function=SendData CommandClass=Configuration) 2015-11-28 10:52:07.8225 Debug 01-0A-00-13-02-03-70-05-6F-05-40-B8 2015-11-28 10:52:07.9234 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:07.9244 Debug 06 2015-11-28 10:52:07.9248 Trace SendDataReady 2015-11-28 10:52:08.0256 Debug 01-07-00-13-40-01-00-0C-A6 2015-11-28 10:52:08.0265 Debug 06 2015-11-28 10:52:08.0269 Trace Error 2015-11-28 10:52:08.0269 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:52:08.0281 Warn Could not deliver message to Node 2 (CallbackId=40 Retry=2) 2015-11-28 10:52:08.1286 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:08.1290 Trace WaitAck 2015-11-28 10:52:08.1290 Trace Sending Message (Node=2 CallbackId=40 Function=SendData CommandClass=Configuration) 2015-11-28 10:52:08.1303 Debug 01-0A-00-13-02-03-70-05-6F-05-40-B8 2015-11-28 10:52:08.2311 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:08.2321 Debug 06 2015-11-28 10:52:08.2321 Trace SendDataReady 2015-11-28 10:52:08.3334 Debug 01-07-00-13-40-01-00-0E-A4 2015-11-28 10:52:08.3345 Debug 06 2015-11-28 10:52:08.3349 Trace Error 2015-11-28 10:52:08.3349 Trace [[[ END REQUEST ]]] took 206 ms 2015-11-28 10:52:08.3362 Warn Delivery of message to Node 2 failed (CallbackId=40). 2015-11-28 10:52:08.3362 Debug 2 Error 2015-11-28 10:52:08.3384 Warn Node is flagged as sleeping message will be re-sent on Wake Up (Node=2 CallbackId=2 Function=40 CommandClass=SendData) 2015-11-28 10:52:08.3391 Trace Adding message 01-0A-00-13-02-03-70-05-6F-05-40-B8 2015-11-28 10:52:08.3391 Debug 2 WakeUpSleepingStatus 1 "2015-11-28 10:52:08.3416 Info HomeAutomation.ZWave 2 Z-Wave Node Status.Error Response timeout!" "2015-11-28 10:52:08.3463 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 1" "2015-11-28 10:52:14.7950 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941327 [CLOSED AFTER 0.068 seconds]" "2015-11-28 10:52:14.7275 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1448699941327 [OPEN]" 2015-11-28 10:52:15.2004 Debug 01-08-00-04-00-02-02-84-07-70 2015-11-28 10:52:15.2013 Debug 06 2015-11-28 10:52:15.2013 Debug 2 WakeUpSleepingStatus 0 2015-11-28 10:52:15.2041 Trace Sending message 0 01-0A-00-13-02-03-70-05-6F-05-40-B8 2015-11-28 10:52:15.2054 Debug 2 WakeUpNotify 1 "2015-11-28 10:52:15.2073 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpSleepingStatus 0" "2015-11-28 10:52:15.2098 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.WakeUpNotify 1" "2015-11-28 10:52:15.2982 Info HomeAutomation.HomeGenie.Automation 91 Automation Program Program.Notification {""Title"":""Z-Wave Node WakeUp""" Message:"Tuin - Multi
2 Battery Get"} 2015-11-28 10:52:15.4973 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:15.4973 Trace WaitAck 2015-11-28 10:52:15.4984 Trace Sending Message (Node=2 CallbackId=41 Function=SendData CommandClass=Configuration) 2015-11-28 10:52:15.4991 Debug 01-0A-00-13-02-03-70-05-6F-05-41-B9 2015-11-28 10:52:15.5103 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:15.5117 Debug 06 2015-11-28 10:52:15.5122 Trace SendDataReady 2015-11-28 10:52:15.6132 Debug 01-07-00-13-41-00-00-02-A8 2015-11-28 10:52:15.6142 Debug 06 2015-11-28 10:52:15.6142 Trace Complete 2015-11-28 10:52:15.6162 Trace [[[ END REQUEST ]]] took 118 ms 2015-11-28 10:52:15.6172 Debug 01-0E-00-04-00-02-08-70-06-6F-04-00-28-DE-80-94 2015-11-28 10:52:15.6188 Debug 06 2015-11-28 10:52:15.6234 Debug 2 Configuration 2678400 "2015-11-28 10:52:15.6337 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Variables.111 2678400" 2015-11-28 10:52:15.7169 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:15.7169 Trace WaitAck 2015-11-28 10:52:15.7179 Trace Sending Message (Node=2 CallbackId=42 Function=SendData CommandClass=Battery) "2015-11-28 10:52:15.7658 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/111/?_=1448699941326 [CLOSED AFTER 8.706 seconds]" 2015-11-28 10:52:15.7201 Debug 01-09-00-13-02-02-80-02-05-42-20 2015-11-28 10:52:15.7337 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:15.7346 Debug 06 2015-11-28 10:52:15.7350 Trace SendDataReady "2015-11-28 10:52:15.8544 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941328 [CLOSED AFTER 0.013 seconds]" "2015-11-28 10:52:15.8409 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.Get/HomeAutomation.HomeGenie.Automation/91/?_=1448699941328 [OPEN]" "2015-11-28 10:52:15.8795 Info WebServiceGateway 192.168.1.103 HTTP GET 304 /hg/html/pages/control/widgets/homegenie/generic/images/unknown.png [CLOSED AFTER 0.005 seconds]" "2015-11-28 10:52:15.8744 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /hg/html/pages/control/widgets/homegenie/generic/images/unknown.png [OPEN]" 2015-11-28 10:52:15.8359 Debug 01-07-00-13-42-00-00-01-A8 2015-11-28 10:52:15.8359 Debug 06 2015-11-28 10:52:15.8371 Trace Complete 2015-11-28 10:52:15.8379 Trace [[[ END REQUEST ]]] took 120 ms 2015-11-28 10:52:16.4396 Debug 01-09-00-04-00-02-03-80-03-4F-3F 2015-11-28 10:52:16.4408 Debug 06 2015-11-28 10:52:16.4408 Debug 2 Battery 79 "2015-11-28 10:52:16.4446 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Battery 79" "2015-11-28 10:52:16.4467 Info HomeAutomation.ZWave 2 ZWave Node Status.Battery 79" "2015-11-28 10:52:18.3560 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/111/?_=1448699941329 [OPEN]" 2015-11-28 10:52:18.4393 Trace [[[ BEGIN REQUEST ]]] 2015-11-28 10:52:18.4398 Trace WaitAck 2015-11-28 10:52:18.4398 Trace Sending Message (Node=2 CallbackId=43 Function=SendData CommandClass=Configuration) 2015-11-28 10:52:18.4410 Debug 01-0A-00-13-02-03-70-05-6F-05-43-BB 2015-11-28 10:52:18.4511 Debug 06-01-04-01-13-01-E8 2015-11-28 10:52:18.4519 Debug 06 2015-11-28 10:52:18.4519 Trace SendDataReady 2015-11-28 10:52:18.5536 Debug 01-07-00-13-43-00-00-01-A9 2015-11-28 10:52:18.5551 Debug 06 2015-11-28 10:52:18.5551 Trace Complete 2015-11-28 10:52:18.5569 Trace [[[ END REQUEST ]]] took 117 ms 2015-11-28 10:52:18.5578 Debug 01-0E-00-04-00-02-08-70-06-6F-04-00-28-DE-80-94 2015-11-28 10:52:18.5590 Debug 06 2015-11-28 10:52:18.5659 Debug 2 Configuration 2678400 "2015-11-28 10:52:18.5682 Info HomeAutomation.ZWave 2 ZWave Node ZWaveNode.Variables.111 2678400" "2015-11-28 10:52:18.7212 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/2/Config.ParameterGet/111/?_=1448699941329 [CLOSED AFTER 0.365 seconds]"