Einbindung eines Sungrow SH10RT: SH05,SH06,SH08,SH10

Begonnen von 3gmann, 05 November 2020, 11:22:14

Vorheriges Thema - Nächstes Thema

FhemPiUser

#30
Danke, aber leider weiterhin ohne Erfolg auf dem LAN-Anschluss mit Interval 30s und folgenden Delay-Konfiguration:


closeAfterResponse 1
dev-timing-commDelay 0.02
dev-timing-sendDelay 0.02
nextOpenDelay 5
queueDelay 0.02


Verbindung ist immer auf disconnected und er bekommt nach der ersten Abfrage keine neuen Werte mehr.
Im Log mit verbose 5 konnte ich keine Ursache finden.

[code]2022.08.15 17:23:24.066 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.06662 / 17:23:24.066
2022.08.15 17:23:24.069 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.60 secs ago
2022.08.15 17:23:24.069 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.070 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.06952 / 17:23:24.069
2022.08.15 17:23:24.070 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.070 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.095 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.09529 / 17:23:24.095
2022.08.15 17:23:24.097 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.63 secs ago
2022.08.15 17:23:24.098 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.098 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.09803 / 17:23:24.098
2022.08.15 17:23:24.099 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.099 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.124 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.12384 / 17:23:24.123
2022.08.15 17:23:24.126 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.66 secs ago
2022.08.15 17:23:24.126 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.127 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.12654 / 17:23:24.126
2022.08.15 17:23:24.127 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.127 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.152 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.15231 / 17:23:24.152
2022.08.15 17:23:24.154 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.69 secs ago
2022.08.15 17:23:24.155 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.155 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.15508 / 17:23:24.155
2022.08.15 17:23:24.156 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.156 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.181 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.18112 / 17:23:24.181
2022.08.15 17:23:24.183 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.72 secs ago
2022.08.15 17:23:24.184 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.184 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.18386 / 17:23:24.183
2022.08.15 17:23:24.184 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.185 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.210 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.20971 / 17:23:24.209
2022.08.15 17:23:24.211 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.74 secs ago
2022.08.15 17:23:24.212 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.213 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.21243 / 17:23:24.212
2022.08.15 17:23:24.213 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.213 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.238 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.23832 / 17:23:24.238
2022.08.15 17:23:24.240 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.77 secs ago
2022.08.15 17:23:24.242 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.243 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.24249 / 17:23:24.242
2022.08.15 17:23:24.243 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.243 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.268 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.26842 / 17:23:24.268
2022.08.15 17:23:24.270 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.80 secs ago
2022.08.15 17:23:24.271 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.271 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.2712 / 17:23:24.271
2022.08.15 17:23:24.272 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.272 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.297 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.29699 / 17:23:24.296
2022.08.15 17:23:24.299 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.83 secs ago
2022.08.15 17:23:24.300 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.300 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.29985 / 17:23:24.299
2022.08.15 17:23:24.300 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.301 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.325 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.32564 / 17:23:24.325
2022.08.15 17:23:24.327 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.86 secs ago
2022.08.15 17:23:24.328 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.329 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.32843 / 17:23:24.328
2022.08.15 17:23:24.329 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.329 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.354 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.35436 / 17:23:24.354
2022.08.15 17:23:24.356 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.89 secs ago
2022.08.15 17:23:24.357 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.357 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.35709 / 17:23:24.357
2022.08.15 17:23:24.358 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.358 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.383 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.38294 / 17:23:24.382
2022.08.15 17:23:24.385 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.92 secs ago
2022.08.15 17:23:24.385 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.386 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.38569 / 17:23:24.385
2022.08.15 17:23:24.386 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.387 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.411 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.41153 / 17:23:24.411
2022.08.15 17:23:24.413 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.95 secs ago
2022.08.15 17:23:24.414 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.414 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.41428 / 17:23:24.414
2022.08.15 17:23:24.415 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.415 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.440 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.44029 / 17:23:24.440
2022.08.15 17:23:24.442 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 14.97 secs ago
2022.08.15 17:23:24.443 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.443 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.44298 / 17:23:24.442
2022.08.15 17:23:24.443 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.444 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.469 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.46878 / 17:23:24.468
2022.08.15 17:23:24.471 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.00 secs ago
2022.08.15 17:23:24.471 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.472 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.47156 / 17:23:24.471
2022.08.15 17:23:24.472 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.472 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.497 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.49732 / 17:23:24.497
2022.08.15 17:23:24.499 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.03 secs ago
2022.08.15 17:23:24.500 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.500 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.50017 / 17:23:24.500
2022.08.15 17:23:24.501 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.501 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.513 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.51351 / 17:23:24.513
2022.08.15 17:23:24.526 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.52586 / 17:23:24.525
2022.08.15 17:23:24.528 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.06 secs ago
2022.08.15 17:23:24.528 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.529 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.52865 / 17:23:24.528
2022.08.15 17:23:24.529 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.530 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.628 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.62805 / 17:23:24.628
2022.08.15 17:23:24.631 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.16 secs ago
2022.08.15 17:23:24.632 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.632 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.63198 / 17:23:24.631
2022.08.15 17:23:24.633 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.633 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.642 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.64239 / 17:23:24.642
2022.08.15 17:23:24.651 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.65073 / 17:23:24.650
2022.08.15 17:23:24.659 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.65911 / 17:23:24.659
2022.08.15 17:23:24.661 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.19 secs ago
2022.08.15 17:23:24.662 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.662 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.66219 / 17:23:24.662
2022.08.15 17:23:24.663 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.663 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.690 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.68966 / 17:23:24.689
2022.08.15 17:23:24.692 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.22 secs ago
2022.08.15 17:23:24.693 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.693 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.69278 / 17:23:24.692
2022.08.15 17:23:24.694 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.694 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.723 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.72273 / 17:23:24.722
2022.08.15 17:23:24.726 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.26 secs ago
2022.08.15 17:23:24.727 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.728 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.72722 / 17:23:24.727
2022.08.15 17:23:24.728 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.729 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.758 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.7577 / 17:23:24.757
2022.08.15 17:23:24.761 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.29 secs ago
2022.08.15 17:23:24.762 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.763 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.76232 / 17:23:24.762
2022.08.15 17:23:24.763 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.764 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.793 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.79295 / 17:23:24.792
2022.08.15 17:23:24.796 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.33 secs ago
2022.08.15 17:23:24.797 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.798 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.79753 / 17:23:24.797
2022.08.15 17:23:24.799 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.799 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.828 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.82797 / 17:23:24.827
2022.08.15 17:23:24.831 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.36 secs ago
2022.08.15 17:23:24.833 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.833 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.83261 / 17:23:24.832
2022.08.15 17:23:24.834 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.834 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.863 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.86325 / 17:23:24.863
2022.08.15 17:23:24.867 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.40 secs ago
2022.08.15 17:23:24.868 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.869 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.86778 / 17:23:24.867
2022.08.15 17:23:24.869 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.870 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.898 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.89842 / 17:23:24.898
2022.08.15 17:23:24.902 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.43 secs ago
2022.08.15 17:23:24.903 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.904 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.90298 / 17:23:24.902
2022.08.15 17:23:24.904 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.905 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.933 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.9334 / 17:23:24.933
2022.08.15 17:23:24.937 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.47 secs ago
2022.08.15 17:23:24.938 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.939 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.93797 / 17:23:24.937
2022.08.15 17:23:24.939 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.940 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:24.969 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.96859 / 17:23:24.968
2022.08.15 17:23:24.972 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.50 secs ago
2022.08.15 17:23:24.973 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:24.974 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577004.97314 / 17:23:24.973
2022.08.15 17:23:24.974 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:24.975 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.004 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.00355 / 17:23:25.003
2022.08.15 17:23:25.007 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.54 secs ago
2022.08.15 17:23:25.007 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.008 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.00764 / 17:23:25.007
2022.08.15 17:23:25.008 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.009 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.033 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.03345 / 17:23:25.033
2022.08.15 17:23:25.035 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.57 secs ago
2022.08.15 17:23:25.036 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.036 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.0362 / 17:23:25.036
2022.08.15 17:23:25.037 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.037 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.062 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.06199 / 17:23:25.061
2022.08.15 17:23:25.064 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.60 secs ago
2022.08.15 17:23:25.064 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.065 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.06471 / 17:23:25.064
2022.08.15 17:23:25.065 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.065 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.090 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.0906 / 17:23:25.090
2022.08.15 17:23:25.092 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.62 secs ago
2022.08.15 17:23:25.093 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.093 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.09327 / 17:23:25.093
2022.08.15 17:23:25.094 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.094 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.119 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.11902 / 17:23:25.119
2022.08.15 17:23:25.121 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.65 secs ago
2022.08.15 17:23:25.121 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.122 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.12172 / 17:23:25.121
2022.08.15 17:23:25.122 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.122 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.147 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.1473 / 17:23:25.147
2022.08.15 17:23:25.149 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.68 secs ago
2022.08.15 17:23:25.150 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.150 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.15006 / 17:23:25.150
2022.08.15 17:23:25.150 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.151 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.176 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.17568 / 17:23:25.175
2022.08.15 17:23:25.177 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.71 secs ago
2022.08.15 17:23:25.178 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.179 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.17842 / 17:23:25.178
2022.08.15 17:23:25.179 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.179 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.204 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.20426 / 17:23:25.204
2022.08.15 17:23:25.206 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.74 secs ago
2022.08.15 17:23:25.207 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.207 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.20706 / 17:23:25.207
2022.08.15 17:23:25.208 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.208 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.233 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.23292 / 17:23:25.232
2022.08.15 17:23:25.235 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.77 secs ago
2022.08.15 17:23:25.235 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.236 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.23565 / 17:23:25.235
2022.08.15 17:23:25.236 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.236 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.261 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.26147 / 17:23:25.261
2022.08.15 17:23:25.263 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.80 secs ago
2022.08.15 17:23:25.264 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.264 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.26421 / 17:23:25.264
2022.08.15 17:23:25.265 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.265 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.290 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.29018 / 17:23:25.290
2022.08.15 17:23:25.292 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.82 secs ago
2022.08.15 17:23:25.293 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.293 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.29288 / 17:23:25.292
2022.08.15 17:23:25.293 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.294 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.319 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.31873 / 17:23:25.318
2022.08.15 17:23:25.321 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.85 secs ago
2022.08.15 17:23:25.321 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.322 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.32147 / 17:23:25.321
2022.08.15 17:23:25.322 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.322 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.347 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.34721 / 17:23:25.347
2022.08.15 17:23:25.349 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.88 secs ago
2022.08.15 17:23:25.350 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.350 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.35001 / 17:23:25.350
2022.08.15 17:23:25.350 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.351 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.376 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.37578 / 17:23:25.375
2022.08.15 17:23:25.378 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.91 secs ago
2022.08.15 17:23:25.378 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 0 NEXT_OPEN 17:23:25.395
2022.08.15 17:23:25.379 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660577005.39528 / 17:23:25.395 and now is 1660577005.37855 / 17:23:25.378
2022.08.15 17:23:25.379 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.379 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.405 5: HttpUtils url=http://192.168.x.x:502/ NonBlocking via http
2022.08.15 17:23:25.406 4: IP: 192.168.x.x-> 192.168.x.x
2022.08.15 17:23:25.410 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.94 secs ago
2022.08.15 17:23:25.410 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.411 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.411 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.437 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 15.97 secs ago
2022.08.15 17:23:25.437 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.438 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.438 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.464 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.00 secs ago
2022.08.15 17:23:25.464 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.465 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.465 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.491 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.02 secs ago
2022.08.15 17:23:25.492 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.492 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.492 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.518 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.05 secs ago
2022.08.15 17:23:25.519 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.519 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.520 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.545 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.08 secs ago
2022.08.15 17:23:25.546 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.546 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.547 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.572 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.10 secs ago
2022.08.15 17:23:25.573 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.573 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.574 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.677 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.21 secs ago
2022.08.15 17:23:25.678 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.678 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.679 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.706 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.24 secs ago
2022.08.15 17:23:25.707 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.707 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.708 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.740 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.27 secs ago
2022.08.15 17:23:25.741 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.742 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.742 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.774 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.31 secs ago
2022.08.15 17:23:25.775 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.776 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.776 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.807 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.34 secs ago
2022.08.15 17:23:25.808 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.808 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.809 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.835 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.37 secs ago
2022.08.15 17:23:25.836 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.836 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.836 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.862 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.39 secs ago
2022.08.15 17:23:25.863 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.863 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.863 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.889 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.42 secs ago
2022.08.15 17:23:25.890 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.890 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.891 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.916 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.45 secs ago
2022.08.15 17:23:25.917 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.917 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.918 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.943 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.48 secs ago
2022.08.15 17:23:25.944 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.944 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.945 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:25.971 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 16.50 secs ago
2022.08.15 17:23:25.971 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:25.972 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:25.

StefanStrobel

Hallo,

in dem Teil des Logs ist die TCP-Verbindung gar nicht erfolgreich geöffnet worden ...
Ist der Modbus-Slave noch blockiert?

Gruß
    Stefan

FhemPiUser

#32
ja, das ist das Problem mutmaßlich, dass die Gegenstelle keine TCP Verbindung mehr zulässt bei falschem Timing und Intervallen <10min. Offenbar ein Fehler im LLC Stack des Wechselrichters

StefanStrobel

Für die Optimierung des Timings bräuchten wir aber einen Auszug aus dem Log, bei dem die Verbindung mal aufgebaut werden konnte bis zu dem Zeitpunkt dass es blockiert.

Gruß
   Stefan

FhemPiUser

Vielen Dank, aber schwierig die relevante Stelle in der Menge an Daten zu finden.

Ich versuche es mal:


2022.08.15 17:23:07.642 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660576989.09829 / 17:23:09.098 and now is 1660576987.64152 / 17:23:
07.641
2022.08.15 17:23:08.464 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660576989.09829 / 17:23:09.098 and now is 1660576988.4637 / 17:23:0
8.463
2022.08.15 17:23:08.724 5: SH10rt_1_LAN: open ignored because DevIo has set NEXT_OPEN to 1660576989.09829 / 17:23:09.098 and now is 1660576988.72441 / 17:23:
08.724
2022.08.15 17:23:09.237 5: HttpUtils url=http://192.168.x.x:502/ NonBlocking via http
2022.08.15 17:23:09.238 4: IP: 192.168.x.x-> 192.168.x.x
2022.08.15 17:23:09.252 4: SH10rt_1_LAN: GetUpdate (V4.4.04 - 17.7.2021) called from Fhem internal timer
2022.08.15 17:23:09.253 4: SH10rt_1_LAN: UpdateTimer called from GetUpdate with cmd next sets timer to call update function in 30.0 sec at 17:23:39.253, inte
rval 30
2022.08.15 17:23:09.262 5: SH10rt_1_LAN: CreateUpdateHash full object list: h13057 h13058 h13083 h13084 h30229 h33046 h33047 i12999 i13000 i13001 i13002 i130
04 i13005 i13007 i13008 i13009 i13010 i13011 i13012 i13016 i13017 i13019 i13020 i13021 i13022 i13023 i13024 i13025 i13026 i13028 i13029 i13033 i13035 i13036
i13039 i13040 i13044 i13045 i13049 i13051 i13053 i13055 i13057 i13059 i13061 i13063 i13065 i13067 i13069 i13071 i13073 i13075 i13077 i5002 i5003 i5007 i5010
i5011 i5012 i5013 i5016 i5030 i5032 i5034 i5035 i5600 i5602 i5604 i5606 i5719 i5720 i5721 i5722 i5723 i5724 i5725 i5746 i5748 i6195 i6196 i6197 i6198 i6199 i
6200 i6201 i6202 i6203 i6204 i6205 i6206 i6207 i6208 i6209 i6210 i6211 i6212 i6213 i6214 i6215 i6216 i6217 i6218 i6219 i6220 i6221 i6222 i6223 i6224 i6225 i6
226 i6227 i6228 i6229 i6230 i6231 i6232 i6233 i6234 i6235 i6236 i6237 i6249 i6251 i6253 i6255 i6257 i6259 i6261 i6263 i6265 i6267 i6269 i6271 i6273 i6275 i62
77 i6279 i6281 i6283 i6285 i6287 i6385 i6386 i6387 i6388 i6389 i6390 i6391 i6392 i6393 i6394 i6395 i6396 i6397 i6398 i6399 i6400 i6401 i6402 i6403 i6404 i640
5 i6406 i6407 i6408 i6409 i6410 i6411 i6412 i6413 i6414 i6415 i6416 i6417 i6418 i6419 i6420 i6421 i6422 i6423 i6424 i6425 i6426 i6427 i6428 i6430 i6432 i6434
i6436 i6438 i6440 i6442 i6444 i6446 i6448 i6450 i6452 i6454 i6456 i6458 i6460 i6462 i6464 i6466 i6468 i6469 i6564 i6565 i6566 i6567 i6568 i6569 i6570 i6571
i6572 i6573 i6574 i6575 i6576 i6577 i6578 i6579 i6580 i6581 i6582 i6583 i6584 i6585 i6586 i6587 i6588 i6589 i6590 i6591 i6592 i6593 i6594 i6595 i6596 i6597 i
6598 i6599 i6600 i6601 i6602 i6603 i6604 i6605 i6606 i6607 i6609 i6611 i6613 i6615 i6617 i6619 i6621 i6623 i6625 i6627 i6629 i6631 i6633 i6635 i6637 i6639 i6
641 i6643 i6645
2022.08.15 17:23:09.274 5: SH10rt_1_LAN: CreateUpdateHash will skip Max_SOC, delay not over (delay 300, 210.283861875534 passed)
...
2022.08.15 17:23:09.422 5: SH10rt_1_LAN: CreateUpdateHash will request i13019 len 1 Battery_Voltage
2022.08.15 17:23:09.423 5: SH10rt_1_LAN: CreateUpdateHash will request i13020 len 1 Battery_Current
2022.08.15 17:23:09.424 5: SH10rt_1_LAN: CreateUpdateHash will request i13021 len 1 03_Battery_Power
2022.08.15 17:23:09.424 5: SH10rt_1_LAN: CreateUpdateHash will request i13022 len 1 Battery_Level
2022.08.15 17:23:09.425 5: SH10rt_1_LAN: CreateUpdateHash will skip Battery_Health, delay not over (delay
...
2022.08.15 17:23:09.441 4: SH10rt_1_LAN: CombineUpdateHash objHash keys before combine: i5016,i5602,i5030,i6468,i5012,i5606,i5034,i13007,i13009,i5604,i13008,
i5010,i5723,i5600,i6469,i5011,i5032,i13019,i5746,i5748,i13022,i5719,i13021,i13020,i5722,i12999,i13000,i5013,i5720,i5725,i5724,i5721,i13010
2022.08.15 17:23:09.442 5: SH10rt_1_LAN: CombineUpdateHash tries to combine read commands
2022.08.15 17:23:09.443 5: SH10rt_1_LAN: CombineUpdateHash combine i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current to span 2, drop read for i5011
2022.08.15 17:23:09.444 5: SH10rt_1_LAN: CombineUpdateHash combine i5010 len 1 MPPT_1_Voltage with i5012 len 1 MPPT_2_Voltage to span 3, drop read for i5012
2022.08.15 17:23:09.445 5: SH10rt_1_LAN: CombineUpdateHash combine i5010 len 1 MPPT_1_Voltage with i5013 len 1 MPPT_2_Current to span 4, drop read for i5013
2022.08.15 17:23:09.445 5: SH10rt_1_LAN: CombineUpdateHash combine i5010 len 1 MPPT_1_Voltage with i5016 len 2 01_Total_DC_Power to span 8, drop read for i50
16
2022.08.15 17:23:09.446 5: SH10rt_1_LAN: CombineUpdateHash cant combine i5010 len 1 MPPT_1_Voltage with i5030 len 2 Wirkleistung, span 22 would be bigger tha
n max 16
2022.08.15 17:23:09.447 5: SH10rt_1_LAN: CombineUpdateHash combine i5030 len 2 Wirkleistung with i5032 len 2 Blindleistung to span 4, drop read for i5032
2022.08.15 17:23:09.447 5: SH10rt_1_LAN: CombineUpdateHash combine i5030 len 2 Wirkleistung with i5034 len 1 Power_Factor to span 5, drop read for i5034
2022.08.15 17:23:09.448 5: SH10rt_1_LAN: CombineUpdateHash cant combine i5030 len 2 Wirkleistung with i5600 len 2 Meter_Active_Power, span 572 would be bigge
r than max 16
2022.08.15 17:23:09.449 5: SH10rt_1_LAN: CombineUpdateHash combine i5600 len 2 Meter_Active_Power with i5602 len 2 Meter_Active_Power_Phase_1 to span 4, drop
read for i5602
2022.08.15 17:23:09.449 5: SH10rt_1_LAN: CombineUpdateHash combine i5600 len 2 Meter_Active_Power with i5604 len 2 Meter_Active_Power_Phase_2 to span 6, drop
read for i5604
2022.08.15 17:23:09.450 5: SH10rt_1_LAN: CombineUpdateHash combine i5600 len 2 Meter_Active_Power with i5606 len 2 Meter_Active_Power_Phase_3 to span 8, drop
read for i5606
2022.08.15 17:23:09.451 5: SH10rt_1_LAN: CombineUpdateHash cant combine i5600 len 2 Meter_Active_Power with i5719 len 1 Backup_Strom_Phase_A, span 120 would
be bigger than max 16
2022.08.15 17:23:09.452 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5720 len 1 Backup_Strom_Phase_B to span 2, drop rea
d for i5720
2022.08.15 17:23:09.452 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5721 len 1 Backup_Strom_Phase_C to span 3, drop rea
d for i5721
2022.08.15 17:23:09.453 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5722 len 1 Backup_Leistung_Phase_A to span 4, drop
read for i5722
2022.08.15 17:23:09.454 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5723 len 1 Backup_Leistung_Phase_B to span 5, drop
read for i5723
2022.08.15 17:23:09.454 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5724 len 1 Backup_Leistung_Phase_C to span 6, drop
read for i5724
2022.08.15 17:23:09.455 5: SH10rt_1_LAN: CombineUpdateHash combine i5719 len 1 Backup_Strom_Phase_A with i5725 len 2 Backup_Gesamtleistung to span 8, drop re
ad for i5725
2022.08.15 17:23:09.456 5: SH10rt_1_LAN: CombineUpdateHash cant combine i5719 len 1 Backup_Strom_Phase_A with i5746 len 2 Meter_Total_Import_Energy, span 29
would be bigger than max 16
2022.08.15 17:23:09.456 5: SH10rt_1_LAN: CombineUpdateHash combine i5746 len 2 Meter_Total_Import_Energy with i5748 len 2 Meter_Total_Export_Energy to span 4
, drop read for i5748
2022.08.15 17:23:09.457 5: SH10rt_1_LAN: CombineUpdateHash cant combine i5746 len 2 Meter_Total_Import_Energy with i6468 len 1 Test_1, span 723 would be bigg
er than max 16
2022.08.15 17:23:09.458 5: SH10rt_1_LAN: CombineUpdateHash combine i6468 len 1 Test_1 with i6469 len 1 Test_2 to span 2, drop read for i6469
2022.08.15 17:23:09.459 5: SH10rt_1_LAN: CombineUpdateHash cant combine i6468 len 1 Test_1 with i12999 len 1 98_System_State, span 6532 would be bigger than
max 16
2022.08.15 17:23:09.459 5: SH10rt_1_LAN: CombineUpdateHash combine i12999 len 1 98_System_State with i13000 len 1 99_Running_State to span 2, drop read for i
13000
2022.08.15 17:23:09.460 5: SH10rt_1_LAN: CombineUpdateHash combine i12999 len 1 98_System_State with i13007 len 2 02_Load_Power to span 10, drop read for i13
007
2022.08.15 17:23:09.460 5: SH10rt_1_LAN: CombineUpdateHash combine i12999 len 1 98_System_State with i13008 len 1 Load_Power_1 to span 10, drop read for i130
08
2022.08.15 17:23:09.461 5: SH10rt_1_LAN: CombineUpdateHash combine i12999 len 1 98_System_State with i13009 len 2 04_Export_Power to span 12, drop read for i
13009
2022.08.15 17:23:09.462 5: SH10rt_1_LAN: CombineUpdateHash combine i12999 len 1 98_System_State with i13010 len 1 Export_Power_1 to span 12, drop read for i1
3010
2022.08.15 17:23:09.462 5: SH10rt_1_LAN: CombineUpdateHash cant combine i12999 len 1 98_System_State with i13019 len 1 Battery_Voltage, span 21 would be bigg
er than max 16
2022.08.15 17:23:09.463 5: SH10rt_1_LAN: CombineUpdateHash combine i13019 len 1 Battery_Voltage with i13020 len 1 Battery_Current to span 2, drop read for i1
3020
2022.08.15 17:23:09.464 5: SH10rt_1_LAN: CombineUpdateHash combine i13019 len 1 Battery_Voltage with i13021 len 1 03_Battery_Power to span 3, drop read for i
13021
2022.08.15 17:23:09.464 5: SH10rt_1_LAN: CombineUpdateHash combine i13019 len 1 Battery_Voltage with i13022 len 1 Battery_Level to span 4, drop read for i130
22
2022.08.15 17:23:09.465 5: SH10rt_1_LAN: CombineUpdateHash keys are now i5030,i6468,i5010,i5600,i13019,i5746,i5719,i12999
2022.08.15 17:23:09.465 4: SH10rt_1_LAN: GetUpdate will now create requests for i5010 len 8 (combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Curr
ent and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), i5030 len 5 (combined i5030 len 2 Wirkleistung with i50
32 len 2 Blindleistung and i5034 len 1 Power_Factor), i5600 len 8 (combined i5600 len 2 Meter_Active_Power with i5602 len 2 Meter_Active_Power_Phase_1 and i5
604 len 2 Meter_Active_Power_Phase_2 and i5606 len 2 Meter_Active_Power_Phase_3), i5719 len 8 (combined i5719 len 1 Backup_Strom_Phase_A with i5720 len 1 Bac
kup_Strom_Phase_B and i5721 len 1 Backup_Strom_Phase_C and i5722 len 1 Backup_Leistung_Phase_A and i5723 len 1 Backup_Leistung_Phase_B and i5724 len 1 Backup
_Leistung_Phase_C and i5725 len 2 Backup_Gesamtleistung), i5746 len 4 (combined i5746 len 2 Meter_Total_Import_Energy with i5748 len 2 Meter_Total_Export_Ene
rgy), i6468 len 2 (combined i6468 len 1 Test_1 with i6469 len 1 Test_2), i12999 len 12 (combined i12999 len 1 98_System_State with i13000 len 1 99_Running_St
ate and i13007 len 2 02_Load_Power and i13008 len 1 Load_Power_1 and i13009 len 2 04_Export_Power and i13010 len 1 Export_Power_1), i13019 len 4 (combined i1
3019 len 1 Battery_Voltage with i13020 len 1 Battery_Current and i13021 len 1 03_Battery_Power and i13022 len 1 Battery_Level)
2022.08.15 17:23:09.467 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5
011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power)
2022.08.15 17:23:09.467 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i5010, qlen 0 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.468 5: SH10rt_1_LAN: StartQueueTimer called from QueueRequest sets internal timer to process queue in 0.000 seconds
2022.08.15 17:23:09.470 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i5030, len 5, tid 121, master device SH10rt_1_LAN, reading Wirkleistung (getUpdate for combined i5030 len 2 Wirkleistung with i5032
len 2 Blindleistung and i5034 len 1 Power_Factor)
2022.08.15 17:23:09.471 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i5030, qlen 1 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.472 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i5600, len 8, tid 26, master device SH10rt_1_LAN, reading Meter_Active_Power (getUpdate for combined i5600 len 2 Meter_Active_Power
with i5602 len 2 Meter_Active_Power_Phase_1 and i5604 len 2 Meter_Active_Power_Phase_2 and i5606 len 2 Meter_Active_Power_Phase_3)
2022.08.15 17:23:09.472 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i5600, qlen 2 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.473 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i5719, len 8, tid 9, master device SH10rt_1_LAN, reading Backup_Strom_Phase_A (getUpdate for combined i5719 len 1 Backup_Strom_Phase
_A with i5720 len 1 Backup_Strom_Phase_B and i5721 len 1 Backup_Strom_Phase_C and i5722 len 1 Backup_Leistung_Phase_A and i5723 len 1 Backup_Leistung_Phase_B
and i5724 len 1 Backup_Leistung_Phase_C and i5725 len 2 Backup_Gesamtleistung)
2022.08.15 17:23:09.474 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i5719, qlen 3 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.475 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i5746, len 4, tid 113, master device SH10rt_1_LAN, reading Meter_Total_Import_Energy (getUpdate for combined i5746 len 2 Meter_Total_Import_Energy with i5748 len 2 Meter_Total_Export_Energy)
2022.08.15 17:23:09.475 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i5746, qlen 4 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.476 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i6468, len 2, tid 37, master device SH10rt_1_LAN, reading Test_1 (getUpdate for combined i6468 len 1 Test_1 with i6469 len 1 Test_2)
2022.08.15 17:23:09.477 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i6468, qlen 5 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.478 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i12999, len 12, tid 46, master device SH10rt_1_LAN, reading 98_System_State (getUpdate for combined i12999 len 1 98_System_State with i13000 len 1 99_Running_State and i13007 len 2 02_Load_Power and i13008 len 1 Load_Power_1 and i13009 len 2 04_Export_Power and i13010 len 1 Export_Power_1)
2022.08.15 17:23:09.478 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i12999, qlen 6 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.479 4: SH10rt_1_LAN: DoRequest called from GetUpdate created new request, read buffer empty, id 1, fCode 4, tid 50,
request: id 1, read fc 4 i13019, len 4, tid 84, master device SH10rt_1_LAN, reading Battery_Voltage (getUpdate for combined i13019 len 1 Battery_Voltage with i13020 len 1 Battery_Current and i13021 len 1 03_Battery_Power and i13022 len 1 Battery_Level)
2022.08.15 17:23:09.480 5: SH10rt_1_LAN: QueueRequest called from DoRequest with i13019, qlen 7 from master SH10rt_1_LAN through io device SH10rt_1_LAN
2022.08.15 17:23:09.619 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 0.15 secs ago
2022.08.15 17:23:09.619 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
2022.08.15 17:23:09.620 5: SH10rt_1_LAN: ProcessRequestQueue will return, device is disconnected, qlen 8, try again in 0.02 seconds
2022.08.15 17:23:09.620 5: SH10rt_1_LAN: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 0.020 seconds
2022.08.15 17:23:10.067 5: SH10rt_1_LAN: ProcessRequestQueue called from Fhem internal timer as queue:SH10rt_1_LAN, qlen 8, request: request: id 1, read fc 4 i5010, len 8, tid 242, master device SH10rt_1_LAN, reading MPPT_1_Voltage (getUpdate for combined i5010 len 1 MPPT_1_Voltage with i5011 len 1 MPPT_1_Current and i5012 len 1 MPPT_2_Voltage and i5013 len 1 MPPT_2_Current and i5016 len 2 01_Total_DC_Power), queued 0.60 secs ago
2022.08.15 17:23:10.067 5: SH10rt_1_LAN: open called from ProcessRequestQueue, busyOpenDev 1
...


Richtiger Ausschnitt?

Falls nein, auf welche Schlüsselwörter muss ich denn achten, um den Ausschnitt für Verbindungsaufbau und die Blockierung zu finden?

StefanStrobel

Leider nein.
Beginnen sollte der Ausschnitt wenn die Verbindung tatsächlich geöffnet wurde ("opened").
Dann bis zum Abbruch der Verbindung ("closed").
CommDelay, sendDelay, busDelay und clientSwitchDelay würde ich in Deinem Fall alle auf 0 setzen.

Gruß
    Stefan

FhemPiUser

Das Log enthält weder "opened" noch "closed", obwohl ich mehr als 1min geloggt habe mit verbose 5 bei einem konfiguriertem Intervall von 30s.

Kommen diese Schlüsselwörter auch, wenn fhem garkeine TCP Modbus Verbindung bekommt vom Wechselrichter?

StefanStrobel

Nein, wenn keine Verbindung aufgebaut werden kann, dann kommt auch kein opened im Log.
Interessant wäre aber gerade was zwischen einem ersten erfolgreichen Verbindungsaufbau und der Blockade passiert.
Ich versuche mal ein paar Optimierungen für Deinen Fall ins Modul zu bauen, vielleicht kommen wir der Sache dann näher.

Gruß
    Stefan

StefanStrobel

Hallo,

anbei eine neue Version des Modbus-Moduls.
Wenn das Attribut closeAfterResponse den Wert 2 hat, dann wird die Verbindung zum Slave sofort nach jedem einzelnen Request wieder getrennt und erst nach Ablauf von queueDelay für den nächsten anstehenden Request wieder geöffnet.
Zum Testen würde ich queueDelay auf 0.02 oder größer setzen und die anderen Delays alle auf 0.
Bin gespannt, ob das as ändert.

Gruß
   Stefan

FhemPiUser

Vielen Dank für dei Mühe!

Ich habe es mal getestet, leider kein Unterschied. Es scheint ein Problem mit der Firmware des Wechselrichters zu sein, andre User haben die gleichen Problem auch mit anderen Clients. Im Prinzip geht es ja, nur halt mit langem Interval. Ich denke das läßt sich nicht durch den Client lösen, sondern ich muss einfach auf ein Firmwareupdate warten und hoffen, dass Sungrow das anpasst.

Immerhin haben sie eine Task Force eröffnet und sich auch dieses Problems angenommen wie es ausieht. Also es gibt Hoffnung.

mähschaf

Hallo und guten Abend,

vielen Dank für Eure Arbeit, ohne die ich sicherlich nichts vergleichbares hinbekommen würde.

Ich bekomme einen SH10RT mit einer Batterie und möchte dieses gerne in FHEM integrieren.

Wenn ich das hier richtig verfolge, kann ich die Werte über die (mäßig gut funktionierende) LAN-MODBUS-Schnittstelle in FHEM als Readings integrieren, indem ich einfach die Definition aus einem der ersten Beiträge kopiere. Dann muss ich nicht über irgendeine Solarcloud etc. gehen, was ich persönlich bevorzugen würde.

Meine Frage (ich habe dazu nichts gefunden): Gibt es eine elegante Lösung, die Holding Register - wenn ich das richtig verstehe insbesondere 13051 und 13052 - über diese Schnittstelle zu setzen?

Hintergrund: Ich könnte mir vorstellen, z. B. Überschussladen und andere Feinheiten darüber zu realisieren.

Danke Euch und einen schönen Abend,
Martin

FhemPiUser

ja, geht alles über modbusattr.

Ich würde überschussladen für ein eauto aber über evcc machen, das ist extra dafür entwickelt.

mähschaf

Zitatja, geht alles über modbusattr.

Danke, klasse!

ZitatIch würde überschussladen für ein eauto aber über evcc machen, das ist extra dafür entwickelt.

Danke, kannte ich nicht, gucke ich mir an. Im Moment hält sich meine Begeisterung noch in Grenzen, zu der ganzen Hardware noch mehr Software zu installieren, aber vielleicht mache ich das später mal. Für's erste würde mir wahrscheinlich ein DOIf reichen....

fhemjörg

#43
Hallo,
ich habe auch ein Sungrow SH10RT Wechselrichter im Einsatz. Ich habe ihn per LAN eingebunden. FHEM sagt auch "opened". Leide bekomme ich keine Readings angezeigt....... ICh habe alle Readings so eingegeben wie in den post weiter oben von Gerhard.
Mein LOG sagt folgendes:
2022.09.12 17:34:25 4: Sungrow: GetUpdate (V4.4.04 - 17.7.2021) called from Fhem internal timer
2022.09.12 17:34:25 4: Sungrow: UpdateTimer called from GetUpdate with cmd next sets timer to call update function in 3.0 sec at 17:34:28.310, interval 3
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash full object list: h05007 h05008 h05009 h05010 h05011 h05012 h05013 h05014 h05015 h05016 h05017 h05018 h05020 i13001 i13002 i13004 i13005 i13007 i13009 i13016 i5007 i5010 i5011 i5012 i5013 i5016 i5035
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5007 len 1 Inside_Temperature
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5010 len 1 MPPT_1_Voltage
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5011 len 1 MPPT_1_Current
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5012 len 1 MPPT_2_Voltage
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5013 len 1 MPPT_2_Current
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5016 len 2 Total_DC_Power
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i5035 len 1 Grid_Frequency
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13001 len 1 Daily_PV_Generation
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13002 len 2 Total_PV_Generation
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13004 len 1 Daily_Export_Energy
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13005 len 2 Total_Export_Energy
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13007 len 2 Load_Power
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13009 len 2 Export_Power
2022.09.12 17:34:25 5: Sungrow: CreateUpdateHash will request i13016 len 1 Daily_direct_Energy_Consumption
2022.09.12 17:34:25 4: Sungrow: CombineUpdateHash objHash keys before combine: i13009,i13001,i13002,i5011,i13007,i13004,i13016,i5035,i5016,i5007,i5012,i13005,i5010,i5013
2022.09.12 17:34:25 5: Sungrow: CombineUpdateHash tries to combine read commands
2022.09.12 17:34:25 5: Sungrow: CombineUpdateHash keys are now i13009,i13001,i13002,i5011,i13007,i13004,i13016,i5035,i5016,i5007,i5012,i13005,i5010,i5013
2022.09.12 17:34:25 4: Sungrow: GetUpdate will now create requests for i5007 len 1 (Inside_Temperature), i5010 len 1 (MPPT_1_Voltage), i5011 len 1 (MPPT_1_Current), i5012 len 1 (MPPT_2_Voltage), i5013 len 1 (MPPT_2_Current), i5016 len 2 (Total_DC_Power), i5035 len 1 (Grid_Frequency), i13001 len 1 (Daily_PV_Generation), i13002 len 2 (Total_PV_Generation), i13004 len 1 (Daily_Export_Energy), i13005 len 2 (Total_Export_Energy), i13007 len 2 (Load_Power), i13009 len 2 (Export_Power), i13016 len 1 (Daily_direct_Energy_Consumption)
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5007, len 1, tid 28, master device Sungrow, reading Inside_Temperature (getUpdate for Inside_Temperature len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5007, qlen 0 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from QueueRequest sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5010, len 1, tid 221, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5010, qlen 1 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5011, len 1, tid 0, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5011, qlen 2 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5012, len 1, tid 66, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5012, qlen 3 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5013, len 1, tid 32, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5013, qlen 4 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5016, len 2, tid 168, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5016, qlen 5 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5035, len 1, tid 47, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i5035, qlen 6 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13001, len 1, tid 119, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13001, qlen 7 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13002, len 2, tid 251, master device Sungrow, reading Total_PV_Generation (getUpdate for Total_PV_Generation len 2)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13002, qlen 8 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13004, len 1, tid 27, master device Sungrow, reading Daily_Export_Energy (getUpdate for Daily_Export_Energy len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13004, qlen 9 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13005, len 2, tid 169, master device Sungrow, reading Total_Export_Energy (getUpdate for Total_Export_Energy len 2)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13005, qlen 10 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13007, len 2, tid 176, master device Sungrow, reading Load_Power (getUpdate for Load_Power len 2)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13007, qlen 11 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13009, len 2, tid 13, master device Sungrow, reading Export_Power (getUpdate for Export_Power len 2)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13009, qlen 12 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13016, len 1, tid 29, master device Sungrow, reading Daily_direct_Energy_Consumption (getUpdate for Daily_direct_Energy_Consumption len 1)
2022.09.12 17:34:25 5: Sungrow: QueueRequest called from DoRequest with i13016, qlen 13 from master Sungrow through io device Sungrow
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 14, request: request: id 1, read fc 4 i5007, len 1, tid 28, master device Sungrow, reading Inside_Temperature (getUpdate for Inside_Temperature len 1), queued 0.03 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 1.538 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 1.543 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 1.538 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 14, sending 001c000000060104138f0001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5007, len 1, tid 28, master device Sungrow, reading Inside_Temperature (getUpdate for Inside_Temperature len 1), queued 0.03 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 001c000000060104138f0001
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 001c00000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 28, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 001c00000002018402, id 1, fCode 132, tid 28,
request: id 1, read fc 4 i5007, len 1, tid 28, master device Sungrow, reading Inside_Temperature (getUpdate for Inside_Temperature len 1), queued 0.04 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 001c00000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 13, request: request: id 1, read fc 4 i5010, len 1, tid 221, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1), queued 0.05 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.007 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.012 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.007 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.093
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 13, request: request: id 1, read fc 4 i5010, len 1, tid 221, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1), queued 0.14 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.105 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.110 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.105 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 13, sending 00dd00000006010413920001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5010, len 1, tid 221, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1), queued 0.15 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 00dd00000006010413920001
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 00dd00000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 221, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 00dd00000002018402, id 1, fCode 132, tid 221,
request: id 1, read fc 4 i5010, len 1, tid 221, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1), queued 0.15 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 00dd00000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 12, request: request: id 1, read fc 4 i5011, len 1, tid 0, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1), queued 0.15 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.009 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 12, request: request: id 1, read fc 4 i5011, len 1, tid 0, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1), queued 0.25 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.108 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 12, sending 000000000006010413930001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5011, len 1, tid 0, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1), queued 0.25 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 000000000006010413930001
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 000000000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 000000000002018402, id 1, fCode 132, tid 0,
request: id 1, read fc 4 i5011, len 1, tid 0, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1), queued 0.26 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 000000000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 11, request: request: id 1, read fc 4 i5012, len 1, tid 66, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1), queued 0.26 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.007 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 11, request: request: id 1, read fc 4 i5012, len 1, tid 66, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1), queued 0.36 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.102 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.102 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.106 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 11, sending 004200000006010413940001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5012, len 1, tid 66, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1), queued 0.36 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 004200000006010413940001
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 004200000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 66, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 004200000002018402, id 1, fCode 132, tid 66,
request: id 1, read fc 4 i5012, len 1, tid 66, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1), queued 0.37 secs ago, sent 0.02 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 004200000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 10, request: request: id 1, read fc 4 i5013, len 1, tid 32, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1), queued 0.38 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.014 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 10, request: request: id 1, read fc 4 i5013, len 1, tid 32, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1), queued 0.48 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.115 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.104 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.104 secs ago, required delay is 0
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 10, sending 002000000006010413950001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5013, len 1, tid 32, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1), queued 0.48 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 002000000006010413950001
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 002000000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 32, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 002000000002018402, id 1, fCode 132, tid 32,
request: id 1, read fc 4 i5013, len 1, tid 32, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1), queued 0.49 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 002000000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 9, request: request: id 1, read fc 4 i5016, len 2, tid 168, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2), queued 0.49 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.005 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.008 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.005 secs ago, required delay is 0.1
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.095
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 9, request: request: id 1, read fc 4 i5016, len 2, tid 168, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2), queued 0.59 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.107 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:25 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 9, sending 00a800000006010413980002 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5016, len 2, tid 168, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2), queued 0.59 secs ago
2022.09.12 17:34:25 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:25 5: DevIo_SimpleWrite Sungrow: 00a800000006010413980002
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:25 5: Sungrow: readFn buffer: 00a800000002018402
2022.09.12 17:34:25 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:25 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 168, dlen 2 and potential data 02
2022.09.12 17:34:25 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:25 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:25 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:25 4: Sungrow: HandleResponse done, current frame / read buffer: 00a800000002018402, id 1, fCode 132, tid 168,
request: id 1, read fc 4 i5016, len 2, tid 168, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2), queued 0.59 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 2
2022.09.12 17:34:25 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:25 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:25 5: Sungrow: DropFrame called from ReadFn - drop 00a800000002018402
2022.09.12 17:34:25 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 8, request: request: id 1, read fc 4 i5035, len 1, tid 47, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1), queued 0.59 secs ago
2022.09.12 17:34:25 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays sendDelay, last send to same device was 0.008 secs ago, required delay is 0.1
2022.09.12 17:34:25 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:25 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:25 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 8, request: request: id 1, read fc 4 i5035, len 1, tid 47, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1), queued 0.69 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.106 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 8, sending 002f00000006010413ab0001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i5035, len 1, tid 47, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1), queued 0.69 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 002f00000006010413ab0001
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 002f00000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 47, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 002f00000002018402, id 1, fCode 132, tid 47,
request: id 1, read fc 4 i5035, len 1, tid 47, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1), queued 0.70 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 002f00000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 7, request: request: id 1, read fc 4 i13001, len 1, tid 119, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1), queued 0.70 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.007 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 7, request: request: id 1, read fc 4 i13001, len 1, tid 119, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1), queued 0.80 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.104 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.107 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.104 secs ago, required delay is 0
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 7, sending 007700000006010432c90001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13001, len 1, tid 119, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1), queued 0.80 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 007700000006010432c90001
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 007700000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 119, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 007700000002018402, id 1, fCode 132, tid 119,
request: id 1, read fc 4 i13001, len 1, tid 119, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1), queued 0.81 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 007700000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 6, request: request: id 1, read fc 4 i13002, len 2, tid 251, master device Sungrow, reading Total_PV_Generation (getUpdate for Total_PV_Generation len 2), queued 0.87 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.060 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.060 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.065 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.040
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 6, request: request: id 1, read fc 4 i13002, len 2, tid 251, master device Sungrow, reading Total_PV_Generation (getUpdate for Total_PV_Generation len 2), queued 0.91 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.104 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.109 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.104 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 6, sending 00fb00000006010432ca0002 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13002, len 2, tid 251, master device Sungrow, reading Total_PV_Generation (getUpdate for Total_PV_Generation len 2), queued 0.92 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 00fb00000006010432ca0002
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 00fb00000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 251, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 00fb00000002018402, id 1, fCode 132, tid 251,
request: id 1, read fc 4 i13002, len 2, tid 251, master device Sungrow, reading Total_PV_Generation (getUpdate for Total_PV_Generation len 2), queued 0.92 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 2
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 00fb00000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 5, request: request: id 1, read fc 4 i13004, len 1, tid 27, master device Sungrow, reading Daily_Export_Energy (getUpdate for Daily_Export_Energy len 1), queued 0.92 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.008 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 5, request: request: id 1, read fc 4 i13004, len 1, tid 27, master device Sungrow, reading Daily_Export_Energy (getUpdate for Daily_Export_Energy len 1), queued 1.02 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.107 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 5, sending 001b00000006010432cc0001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13004, len 1, tid 27, master device Sungrow, reading Daily_Export_Energy (getUpdate for Daily_Export_Energy len 1), queued 1.02 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 001b00000006010432cc0001
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 001b00000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 27, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 001b00000002018402, id 1, fCode 132, tid 27,
request: id 1, read fc 4 i13004, len 1, tid 27, master device Sungrow, reading Daily_Export_Energy (getUpdate for Daily_Export_Energy len 1), queued 1.03 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 001b00000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 4, request: request: id 1, read fc 4 i13005, len 2, tid 169, master device Sungrow, reading Total_Export_Energy (getUpdate for Total_Export_Energy len 2), queued 1.03 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.009 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 4, request: request: id 1, read fc 4 i13005, len 2, tid 169, master device Sungrow, reading Total_Export_Energy (getUpdate for Total_Export_Energy len 2), queued 1.13 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.108 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 4, sending 00a900000006010432cd0002 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13005, len 2, tid 169, master device Sungrow, reading Total_Export_Energy (getUpdate for Total_Export_Energy len 2), queued 1.13 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 00a900000006010432cd0002
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 00a900000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 169, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 00a900000002018402, id 1, fCode 132, tid 169,
request: id 1, read fc 4 i13005, len 2, tid 169, master device Sungrow, reading Total_Export_Energy (getUpdate for Total_Export_Energy len 2), queued 1.14 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 2
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 00a900000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 3, request: request: id 1, read fc 4 i13007, len 2, tid 176, master device Sungrow, reading Load_Power (getUpdate for Load_Power len 2), queued 1.14 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.008 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 3, request: request: id 1, read fc 4 i13007, len 2, tid 176, master device Sungrow, reading Load_Power (getUpdate for Load_Power len 2), queued 1.24 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.107 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 3, sending 00b000000006010432cf0002 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13007, len 2, tid 176, master device Sungrow, reading Load_Power (getUpdate for Load_Power len 2), queued 1.24 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 00b000000006010432cf0002
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 00b000000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 176, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 00b000000002018402, id 1, fCode 132, tid 176,
request: id 1, read fc 4 i13007, len 2, tid 176, master device Sungrow, reading Load_Power (getUpdate for Load_Power len 2), queued 1.25 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 2
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 00b000000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 2, request: request: id 1, read fc 4 i13009, len 2, tid 13, master device Sungrow, reading Export_Power (getUpdate for Export_Power len 2), queued 1.25 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.004 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.007 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.004 secs ago, required delay is 0.1
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.096
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 2, request: request: id 1, read fc 4 i13009, len 2, tid 13, master device Sungrow, reading Export_Power (getUpdate for Export_Power len 2), queued 1.35 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.106 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 2, sending 000d00000006010432d10002 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13009, len 2, tid 13, master device Sungrow, reading Export_Power (getUpdate for Export_Power len 2), queued 1.35 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 000d00000006010432d10002
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from ProcessRequestQueue sets internal timer to process queue in 1.000 seconds
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 000d00000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 13, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 000d00000002018402, id 1, fCode 132, tid 13,
request: id 1, read fc 4 i13009, len 2, tid 13, master device Sungrow, reading Export_Power (getUpdate for Export_Power len 2), queued 1.36 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 2
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: StartQueueTimer called from HandleResponse sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 000d00000002018402
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 1, request: request: id 1, read fc 4 i13016, len 1, tid 29, master device Sungrow, reading Daily_direct_Energy_Consumption (getUpdate for Daily_direct_Energy_Consumption len 1), queued 1.36 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.006 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.012 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.006 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: checkDelays found commDelay not over, set timer to try again in 0.094
2022.09.12 17:34:26 5: Sungrow: ProcessRequestQueue called from Fhem internal timer as queue:Sungrow, qlen 1, request: request: id 1, read fc 4 i13016, len 1, tid 29, master device Sungrow, reading Daily_direct_Energy_Consumption (getUpdate for Daily_direct_Energy_Consumption len 1), queued 1.46 secs ago
2022.09.12 17:34:26 5: Sungrow: checkDelays sendDelay, last send to same device was 0.109 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays commDelay, last communication with same device was 0.103 secs ago, required delay is 0.1
2022.09.12 17:34:26 5: Sungrow: checkDelays busDelayRead, last activity on bus was 0.103 secs ago, required delay is 0
2022.09.12 17:34:26 5: Sungrow: checkDelays clientSwitchDelay is not relevant
2022.09.12 17:34:26 4: Sungrow: ProcessRequestQueue (V4.4.04 - 17.7.2021) qlen 1, sending 001d00000006010432d80001 via 192.168.17.76:502, read buffer empty,
request: id 1, read fc 4 i13016, len 1, tid 29, master device Sungrow, reading Daily_direct_Energy_Consumption (getUpdate for Daily_direct_Energy_Consumption len 1), queued 1.46 secs ago
2022.09.12 17:34:26 5: Sungrow: Send called from ProcessRequestQueue
2022.09.12 17:34:26 5: DevIo_SimpleWrite Sungrow: 001d00000006010432d80001
2022.09.12 17:34:26 5: Sungrow: readFn buffer: 001d00000002018402
2022.09.12 17:34:26 5: Sungrow: ParseFrameStart called from ReadFn protocol TCP expecting id 1
2022.09.12 17:34:26 4: Sungrow: ParseFrameStart (TCP, master) extracted id 1, fCode 132, tid 29, dlen 2 and potential data 02
2022.09.12 17:34:26 5: Sungrow: HandleResponse called from ReadFn
2022.09.12 17:34:26 5: Sungrow: ParseResponse called from HandleResponse
2022.09.12 17:34:26 4: Sungrow: HandleResponse got response with error code 84 / 02, illegal data address
2022.09.12 17:34:26 4: Sungrow: HandleResponse done, current frame / read buffer: 001d00000002018402, id 1, fCode 132, tid 29,
request: id 1, read fc 4 i13016, len 1, tid 29, master device Sungrow, reading Daily_direct_Energy_Consumption (getUpdate for Daily_direct_Energy_Consumption len 1), queued 1.46 secs ago, sent 0.01 secs ago,
response: id 1, fc 132, error code 02, len 1
2022.09.12 17:34:26 5: Sungrow: ResetExpect for HandleResponse from response to idle
2022.09.12 17:34:26 5: Sungrow: DropFrame called from ReadFn - drop 001d00000002018402
2022.09.12 17:34:28 4: Sungrow: GetUpdate (V4.4.04 - 17.7.2021) called from Fhem internal timer
2022.09.12 17:34:28 4: Sungrow: UpdateTimer called from GetUpdate with cmd next sets timer to call update function in 3.0 sec at 17:34:31.311, interval 3
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash full object list: h05007 h05008 h05009 h05010 h05011 h05012 h05013 h05014 h05015 h05016 h05017 h05018 h05020 i13001 i13002 i13004 i13005 i13007 i13009 i13016 i5007 i5010 i5011 i5012 i5013 i5016 i5035
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5007 len 1 Inside_Temperature
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5010 len 1 MPPT_1_Voltage
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5011 len 1 MPPT_1_Current
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5012 len 1 MPPT_2_Voltage
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5013 len 1 MPPT_2_Current
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5016 len 2 Total_DC_Power
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i5035 len 1 Grid_Frequency
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13001 len 1 Daily_PV_Generation
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13002 len 2 Total_PV_Generation
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13004 len 1 Daily_Export_Energy
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13005 len 2 Total_Export_Energy
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13007 len 2 Load_Power
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13009 len 2 Export_Power
2022.09.12 17:34:28 5: Sungrow: CreateUpdateHash will request i13016 len 1 Daily_direct_Energy_Consumption
2022.09.12 17:34:28 4: Sungrow: CombineUpdateHash objHash keys before combine: i13007,i5011,i13002,i13001,i13009,i5013,i5010,i5012,i13005,i5007,i5035,i5016,i13016,i13004
2022.09.12 17:34:28 5: Sungrow: CombineUpdateHash tries to combine read commands
2022.09.12 17:34:28 5: Sungrow: CombineUpdateHash keys are now i13007,i5011,i13002,i13001,i13009,i5013,i5010,i5012,i13005,i5007,i5035,i5016,i13016,i13004
2022.09.12 17:34:28 4: Sungrow: GetUpdate will now create requests for i5007 len 1 (Inside_Temperature), i5010 len 1 (MPPT_1_Voltage), i5011 len 1 (MPPT_1_Current), i5012 len 1 (MPPT_2_Voltage), i5013 len 1 (MPPT_2_Current), i5016 len 2 (Total_DC_Power), i5035 len 1 (Grid_Frequency), i13001 len 1 (Daily_PV_Generation), i13002 len 2 (Total_PV_Generation), i13004 len 1 (Daily_Export_Energy), i13005 len 2 (Total_Export_Energy), i13007 len 2 (Load_Power), i13009 len 2 (Export_Power), i13016 len 1 (Daily_direct_Energy_Consumption)
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5007, len 1, tid 253, master device Sungrow, reading Inside_Temperature (getUpdate for Inside_Temperature len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5007, qlen 0 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 5: Sungrow: StartQueueTimer called from QueueRequest sets internal timer to process queue in 0.000 seconds
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5010, len 1, tid 125, master device Sungrow, reading MPPT_1_Voltage (getUpdate for MPPT_1_Voltage len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5010, qlen 1 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5011, len 1, tid 75, master device Sungrow, reading MPPT_1_Current (getUpdate for MPPT_1_Current len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5011, qlen 2 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5012, len 1, tid 93, master device Sungrow, reading MPPT_2_Voltage (getUpdate for MPPT_2_Voltage len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5012, qlen 3 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5013, len 1, tid 110, master device Sungrow, reading MPPT_2_Current (getUpdate for MPPT_2_Current len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5013, qlen 4 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5016, len 2, tid 89, master device Sungrow, reading Total_DC_Power (getUpdate for Total_DC_Power len 2)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5016, qlen 5 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i5035, len 1, tid 68, master device Sungrow, reading Grid_Frequency (getUpdate for Grid_Frequency len 1)
2022.09.12 17:34:28 5: Sungrow: QueueRequest called from DoRequest with i5035, qlen 6 from master Sungrow through io device Sungrow
2022.09.12 17:34:28 4: Sungrow: DoRequest called from GetUpdate created new request, read buffer empty,
request: id 1, read fc 4 i13001, len 1, tid 77, master device Sungrow, reading Daily_PV_Generation (getUpdate for Daily_PV_Generation len 1)

kann mir jemand hier weiter helfen?
Gruß Jörg

FhemPiUser

Kannst Du mal Deine modbusattr-device-config posten? Mit welchem Intervall fragst Du ab?