The strange thing is that yesterday evening after installing r505, my z-wave modules did not allow proper read (continuously give timeout on read actions):
2015-12-06 21:09:44.2339 Debug ZWaveMessage (RawData=01-09-00-13-04-02-20-02-05-B4-70)
2015-12-06 21:09:44.2346 Debug ZWaveMessage (Direction=Outbound
2015-12-06 21:09:44.6774 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:44.6778 Trace WaitAck
2015-12-06 21:09:44.6778 Trace Sending Message (Node=4
2015-12-06 21:09:44.6791 Debug 01-09-00-13-04-02-20-02-05-B4-70
2015-12-06 21:09:44.7801 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:44.7810 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:44.7810 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:44.7832 Debug 06
2015-12-06 21:09:44.7838 Trace SendDataReady
2015-12-06 21:09:44.8847 Debug 01-07-00-13-B4-01-00-0D-53
2015-12-06 21:09:44.8856 Debug ZWaveMessage (RawData=01-07-00-13-B4-01-00-0D-53)
2015-12-06 21:09:44.8856 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:44.8869 Debug 06
2015-12-06 21:09:44.8869 Trace Error
2015-12-06 21:09:44.8882 Trace [[[ END REQUEST ]]] took 210 ms
2015-12-06 21:09:44.8887 Warn Could not deliver message to Node 4 (CallbackId=B4
2015-12-06 21:09:45.0893 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:45.0898 Trace WaitAck
2015-12-06 21:09:45.0898 Trace Sending Message (Node=4
2015-12-06 21:09:45.0910 Debug 01-09-00-13-04-02-20-02-05-B4-70
2015-12-06 21:09:45.1919 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:45.1929 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:45.1929 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:45.1941 Debug 06
2015-12-06 21:09:45.1946 Trace SendDataReady
2015-12-06 21:09:45.2953 Debug 01-07-00-13-B4-01-00-0B-55
2015-12-06 21:09:45.2964 Debug ZWaveMessage (RawData=01-07-00-13-B4-01-00-0B-55)
2015-12-06 21:09:45.2968 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:45.2977 Debug 06
2015-12-06 21:09:45.2977 Trace Error
2015-12-06 21:09:45.2988 Trace [[[ END REQUEST ]]] took 208 ms
2015-12-06 21:09:45.2988 Warn Could not deliver message to Node 4 (CallbackId=B4
2015-12-06 21:09:45.5000 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:45.5539 Trace WaitAck
2015-12-06 21:09:45.5547 Trace Sending Message (Node=4
2015-12-06 21:09:45.5556 Debug 01-09-00-13-04-02-20-02-05-B4-70
2015-12-06 21:09:45.6454 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:45.6462 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:45.6466 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:45.6466 Debug 06
2015-12-06 21:09:45.6479 Trace SendDataReady
2015-12-06 21:09:45.7487 Debug 01-07-00-13-B4-01-00-0E-50
2015-12-06 21:09:45.7498 Debug ZWaveMessage (RawData=01-07-00-13-B4-01-00-0E-50)
2015-12-06 21:09:45.7498 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:45.7510 Debug 06
2015-12-06 21:09:45.7510 Trace Error
2015-12-06 21:09:45.7522 Trace [[[ END REQUEST ]]] took 198 ms
2015-12-06 21:09:45.7527 Warn Delivery of message to Node 4 failed (CallbackId=B4).
2015-12-06 21:09:45.7527 Debug 4 Error
"2015-12-06 21:09:45.7550 Info HomeAutomation.ZWave 4 Z-Wave Node Status.Error Response timeout!"
2015-12-06 21:09:54.2381 Debug ZWaveMessage (RawData=01-09-00-13-05-02-20-02-05-B5-70)
2015-12-06 21:09:54.2389 Debug ZWaveMessage (Direction=Outbound
2015-12-06 21:09:54.3574 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:54.3579 Trace WaitAck
2015-12-06 21:09:54.3579 Trace Sending Message (Node=5
2015-12-06 21:09:54.3591 Debug 01-09-00-13-05-02-20-02-05-B5-70
2015-12-06 21:09:54.3700 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:54.3709 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:54.3709 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:54.3721 Debug 06
2015-12-06 21:09:54.3729 Trace SendDataReady
2015-12-06 21:09:54.4737 Debug 01-07-00-13-B5-01-00-0A-55
2015-12-06 21:09:54.4746 Debug ZWaveMessage (RawData=01-07-00-13-B5-01-00-0A-55)
2015-12-06 21:09:54.4746 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:54.4758 Debug 06
2015-12-06 21:09:54.4758 Trace Error
2015-12-06 21:09:54.4774 Trace [[[ END REQUEST ]]] took 119 ms
2015-12-06 21:09:54.4780 Warn Could not deliver message to Node 5 (CallbackId=B5
2015-12-06 21:09:54.6786 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:54.6786 Trace WaitAck
2015-12-06 21:09:54.6797 Trace Sending Message (Node=5
2015-12-06 21:09:54.6797 Debug 01-09-00-13-05-02-20-02-05-B5-70
2015-12-06 21:09:54.7828 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:54.7861 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:54.7865 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:54.7865 Debug 06
2015-12-06 21:09:54.7877 Trace SendDataReady
"2015-12-06 21:09:54.8740 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.KwLoad 0.000"
"2015-12-06 21:09:54.8821 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.WattLoad 0.000"
2015-12-06 21:09:54.8885 Debug 01-07-00-13-B5-01-00-0B-54
2015-12-06 21:09:54.8885 Debug ZWaveMessage (RawData=01-07-00-13-B5-01-00-0B-54)
2015-12-06 21:09:54.8904 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:54.8913 Debug 06
2015-12-06 21:09:54.8917 Trace Error
2015-12-06 21:09:54.8925 Trace [[[ END REQUEST ]]] took 213 ms
2015-12-06 21:09:54.8925 Warn Could not deliver message to Node 5 (CallbackId=B5
"2015-12-06 21:09:54.9215 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingDevices 0"
"2015-12-06 21:09:54.9753 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingLights 0"
"2015-12-06 21:09:55.0223 Info HomeAutomation.EnergyMonitor 1 Virtual Module EnergyMonitor.OperatingSwitches 0"
2015-12-06 21:09:55.0951 Trace [[[ BEGIN REQUEST ]]]
2015-12-06 21:09:55.0956 Trace WaitAck
2015-12-06 21:09:55.0956 Trace Sending Message (Node=5
2015-12-06 21:09:55.0969 Debug 01-09-00-13-05-02-20-02-05-B5-70
2015-12-06 21:09:55.1977 Debug 06-01-04-01-13-01-E8
2015-12-06 21:09:55.2014 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-06 21:09:55.2020 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:55.2029 Debug 06
2015-12-06 21:09:55.2029 Trace SendDataReady
2015-12-06 21:09:55.3040 Debug 01-07-00-13-B5-01-00-0D-52
2015-12-06 21:09:55.3051 Debug ZWaveMessage (RawData=01-07-00-13-B5-01-00-0D-52)
2015-12-06 21:09:55.3051 Debug ZWaveMessage (Direction=Inbound
2015-12-06 21:09:55.3063 Debug 06
2015-12-06 21:09:55.3067 Trace Error
2015-12-06 21:09:55.3067 Trace [[[ END REQUEST ]]] took 211 ms
2015-12-06 21:09:55.3079 Warn Delivery of message to Node 5 failed (CallbackId=B5).
2015-12-06 21:09:55.3079 Debug 5 Error
However, today without anything changed, they are functional again:
2015-12-07 20:12:49.4948 Trace [[[ BEGIN REQUEST ]]]
2015-12-07 20:12:49.4948 Trace WaitAck
2015-12-07 20:12:49.4958 Trace Sending Message (Node=4
2015-12-07 20:12:49.4958 Debug 01-09-00-13-04-02-72-04-05-C6-56
"2015-12-07 20:12:49.0926 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/ManufacturerSpecific.Get/?_=1449515377586 [OPEN]"
2015-12-07 20:12:49.0947 Debug ZWaveMessage (RawData=01-09-00-13-04-02-72-04-05-C6-56)
2015-12-07 20:12:49.0953 Debug ZWaveMessage (Direction=Outbound
2015-12-07 20:12:49.5735 Debug 06-01-04-01-13-01-E8
2015-12-07 20:12:49.5744 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-07 20:12:49.5748 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:49.5758 Debug 06
2015-12-07 20:12:49.5758 Trace SendDataReady
"2015-12-07 20:12:49.8062 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/ManufacturerSpecific.Get/?_=1449515377586 [CLOSED AFTER 0.713 seconds]"
2015-12-07 20:12:49.6769 Debug 01-07-00-13-C6-00-00-01-2C
2015-12-07 20:12:49.6778 Debug ZWaveMessage (RawData=01-07-00-13-C6-00-00-01-2C)
2015-12-07 20:12:49.6778 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:49.6791 Debug 06
2015-12-07 20:12:49.6791 Trace Complete
2015-12-07 20:12:49.6804 Trace [[[ END REQUEST ]]] took 185 ms
2015-12-07 20:12:49.6813 Debug 01-0E-00-04-00-04-08-72-05-01-0F-04-00-10-0A-9E
2015-12-07 20:12:49.6822 Debug ZWaveMessage (RawData=01-0E-00-04-00-04-08-72-05-01-0F-04-00-10-0A-9E)
2015-12-07 20:12:49.6822 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:49.6835 Debug 06
2015-12-07 20:12:49.6919 Debug NodeUpdated (NodeId=4
"2015-12-07 20:12:49.6941 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.ManufacturerSpecific 010F:0400:100A"
"2015-12-07 20:12:49.8381 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/NodeInfo.Get/?_=1449515377587 [OPEN]"
2015-12-07 20:12:49.9121 Debug Node 4
2015-12-07 20:12:49.9130 Debug ZWaveMessage (RawData=01-04-00-60-04-9F)
2015-12-07 20:12:49.9130 Debug ZWaveMessage (Direction=Outbound
2015-12-07 20:12:50.3369 Trace [[[ BEGIN REQUEST ]]]
2015-12-07 20:12:50.3369 Trace WaitAck
2015-12-07 20:12:50.3380 Trace Sending Message (Node=4
2015-12-07 20:12:50.3380 Debug 01-04-00-60-04-9F
2015-12-07 20:12:50.3495 Debug 06-01-04-01-60-01-9B
2015-12-07 20:12:50.3503 Debug ZWaveMessage (RawData=01-04-01-60-01-9B)
2015-12-07 20:12:50.3503 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:50.3516 Debug 06
2015-12-07 20:12:50.3519 Trace SendDataReady
2015-12-07 20:12:50.4527 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-12-07 20:12:50.4539 Debug ZWaveMessage (RawData=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-12-07 20:12:50.4539 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:50.4553 Debug 06
2015-12-07 20:12:50.4638 Debug NodeUpdated (NodeId=4
2015-12-07 20:12:50.4658 Trace Complete
2015-12-07 20:12:50.4658 Trace [[[ END REQUEST ]]] took 129 ms
"2015-12-07 20:12:50.4673 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-12-07 20:12:50.6089 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/NodeInfo.Get/?_=1449515377587 [CLOSED AFTER 0.771 seconds]"
2015-12-07 20:12:51.1110 Trace [[[ BEGIN REQUEST ]]]
2015-12-07 20:12:51.1110 Trace WaitAck
2015-12-07 20:12:51.1121 Trace Sending Message (Node=4
2015-12-07 20:12:51.1129 Debug 01-0A-00-13-04-03-60-04-25-05-C7-62
"2015-12-07 20:12:50.6262 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1449515377588 [OPEN]"
2015-12-07 20:12:50.6161 Debug ZWaveMessage (RawData=01-0A-00-13-04-03-60-04-25-05-C7-62)
2015-12-07 20:12:50.6161 Debug ZWaveMessage (Direction=Outbound
2015-12-07 20:12:51.2101 Debug 06-01-04-01-13-01-E8
2015-12-07 20:12:51.2109 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-07 20:12:51.2109 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:51.2121 Debug 06
2015-12-07 20:12:51.2121 Trace SendDataReady
2015-12-07 20:12:51.3133 Debug 01-07-00-13-C7-00-00-02-2E
2015-12-07 20:12:51.3143 Debug ZWaveMessage (RawData=01-07-00-13-C7-00-00-02-2E)
2015-12-07 20:12:51.3143 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:51.3155 Debug 06
2015-12-07 20:12:51.3159 Trace Complete
2015-12-07 20:12:51.3173 Trace [[[ END REQUEST ]]] took 205 ms
2015-12-07 20:12:51.3182 Debug 01-0A-00-04-00-04-04-60-05-25-02-B3
2015-12-07 20:12:51.3245 Debug ZWaveMessage (RawData=01-0A-00-04-00-04-04-60-05-25-02-B3)
2015-12-07 20:12:51.3250 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:51.3258 Debug 06
2015-12-07 20:12:51.3258 Debug NodeUpdated (NodeId=4
"2015-12-07 20:12:51.3349 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.MultiInstance.SwitchBinary.Count 2"
"2015-12-07 20:12:55.2746 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Modules.List/?_=1449515377588 [CLOSED AFTER 4.648 seconds]"
"2015-12-07 20:12:58.0664 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Association.Get/1/?_=1449515377589 [OPEN]"
2015-12-07 20:12:58.0692 Debug ZWaveMessage (RawData=01-0A-00-13-04-03-85-02-01-05-C8-AA)
2015-12-07 20:12:58.0699 Debug ZWaveMessage (Direction=Outbound
2015-12-07 20:12:58.2302 Trace [[[ BEGIN REQUEST ]]]
2015-12-07 20:12:58.2302 Trace WaitAck
2015-12-07 20:12:58.2311 Trace Sending Message (Node=4
2015-12-07 20:12:58.2318 Debug 01-0A-00-13-04-03-85-02-01-05-C8-AA
2015-12-07 20:12:58.2912 Debug 06-01-04-01-13-01-E8
2015-12-07 20:12:58.2920 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-07 20:12:58.2920 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:58.2932 Debug 06
2015-12-07 20:12:58.2932 Trace SendDataReady
2015-12-07 20:12:58.2952 Debug 01-0C-00-04-00-04-06-85-03-01-05-00-01-76
2015-12-07 20:12:58.2961 Debug ZWaveMessage (RawData=01-0C-00-04-00-04-06-85-03-01-05-00-01-76)
2015-12-07 20:12:58.2961 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:58.2974 Debug 06
2015-12-07 20:12:58.3048 Debug NodeUpdated (NodeId=4
"2015-12-07 20:12:58.3080 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Max 5"
"2015-12-07 20:12:58.3106 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Count 0"
2015-12-07 20:12:58.3257 Debug 01-07-00-13-C8-00-00-05-26
"2015-12-07 20:12:58.3263 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.1 1"
2015-12-07 20:12:58.3279 Debug ZWaveMessage (RawData=01-07-00-13-C8-00-00-05-26)
2015-12-07 20:12:58.3279 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:58.3293 Debug 06
2015-12-07 20:12:58.3293 Trace Complete
2015-12-07 20:12:58.3313 Trace [[[ END REQUEST ]]] took 100 ms
2015-12-07 20:12:58.3321 Debug 01-0C-00-04-00-04-06-85-03-01-05-00-01-76
2015-12-07 20:12:58.3351 Debug ZWaveMessage (RawData=01-0C-00-04-00-04-06-85-03-01-05-00-01-76)
2015-12-07 20:12:58.3351 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:12:58.3365 Debug 06
2015-12-07 20:12:58.3370 Debug NodeUpdated (NodeId=4
"2015-12-07 20:12:58.3857 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Max 5"
"2015-12-07 20:12:58.4138 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.Count 0"
"2015-12-07 20:12:58.4327 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Associations.1 1"
"2015-12-07 20:12:58.6030 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Association.Get/1/?_=1449515377589 [CLOSED AFTER 0.537 seconds]"
"2015-12-07 20:12:58.9797 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1449515377590 [OPEN]"
"2015-12-07 20:12:58.9958 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.HomeGenie/Config/Interfaces.List/?_=1449515377590 [CLOSED AFTER 0.016 seconds]"
"2015-12-07 20:13:06.4506 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Config.ParameterGet/1/?_=1449515377591 [OPEN]"
2015-12-07 20:13:06.4544 Debug ZWaveMessage (RawData=01-0A-00-13-04-03-70-05-01-05-C9-59)
2015-12-07 20:13:06.4548 Debug ZWaveMessage (Direction=Outbound
2015-12-07 20:13:06.9344 Trace [[[ BEGIN REQUEST ]]]
2015-12-07 20:13:06.9349 Trace WaitAck
2015-12-07 20:13:06.9349 Trace Sending Message (Node=4
2015-12-07 20:13:06.9361 Debug 01-0A-00-13-04-03-70-05-01-05-C9-59
2015-12-07 20:13:06.9513 Debug 06-01-04-01-13-01-E8
2015-12-07 20:13:06.9522 Debug ZWaveMessage (RawData=01-04-01-13-01-E8)
2015-12-07 20:13:06.9522 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:13:06.9535 Debug 06
2015-12-07 20:13:06.9538 Trace SendDataReady
"2015-12-07 20:13:07.2272 Info WebServiceGateway 192.168.1.103 HTTP GET 200 /api/HomeAutomation.ZWave/4/Config.ParameterGet/1/?_=1449515377591 [CLOSED AFTER 0.777 seconds]"
2015-12-07 20:13:07.0549 Debug 01-07-00-13-C9-00-00-01-23
2015-12-07 20:13:07.0549 Debug ZWaveMessage (RawData=01-07-00-13-C9-00-00-01-23)
2015-12-07 20:13:07.0561 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:13:07.0569 Debug 06
2015-12-07 20:13:07.0569 Trace Complete
2015-12-07 20:13:07.0584 Trace [[[ END REQUEST ]]] took 123 ms
2015-12-07 20:13:07.0593 Debug 01-0B-00-04-00-04-05-70-06-01-01-FF-78
2015-12-07 20:13:07.0602 Debug ZWaveMessage (RawData=01-0B-00-04-00-04-05-70-06-01-01-FF-78)
2015-12-07 20:13:07.0602 Debug ZWaveMessage (Direction=Inbound
2015-12-07 20:13:07.0615 Debug 06
2015-12-07 20:13:07.0874 Debug NodeUpdated (NodeId=4
"2015-12-07 20:13:07.0897 Info HomeAutomation.ZWave 4 ZWave Node ZWaveNode.Variables.1 255"