2022-01-31 10:35:30.096  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-01-31 10:35:30.097  - debug: apsystems-ecu.0 (742) Ecu.trConnect() hide=false, ip=10.0.0.178 port=8899
2022-01-31 10:35:30.102  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-01-31 10:35:30.103  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService/216000054025) stSystemInfo->stSystemInfo
2022-01-31 10:35:30.103  - debug: apsystems-ecu.0 (742) Ecu.reqService(SYSTEMINFO req:APS1100160001END)
2022-01-31 10:35:30.124  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 21ms - 41,50,53,31,31,30,30,39,34,30,30,30,31,32,31,36,30,30,30,30,35,34,30,32,35,30,31,0,0,4f,6b,0,0,0,75,0,0,0,c,d0,d0,d0,d0,d0,d0,d0,0,4,0,4,31,30,30,31,32,45,43,55,5f,52,5f,31,2e,32,2e,31,39,30,30,39,45,74,63,2f,47,4d,54,2d,38,80,97,1b,1,cc,b2,0,0,0,0,0,0,45,4e,44
2022-01-31 10:35:30.125  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessSystemInfo() - done
2022-01-31 10:35:30.126  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:35:30.126  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stSystemInfo->stRealTimeData
2022-01-31 10:35:30.126  - debug: apsystems-ecu.0 (742) Ecu.reqService(REAL_TIME_DATA req:APS1100280002216000054025END)
2022-01-31 10:35:30.185  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 58ms - 41,50,53,31,31,30,31,31,33,30,30,30,32,30,30,30,31,0,4,20,22,1,31,10,30,42,40,80,0,8,98,23,1,30,31,1,f3,0,69,0,11,0,ea,0,f,0,ea,40,80,0,9,50,33,1,30,31,1,f3,0,69,0,10,0,ea,0,e,0,ea,40,80,0,9,47,6,1,30,31,1,f3,0,68,0,c,0,e9,0,e,0,e9,40,80,0,12,16,99,1,30,31,1,f3,0,69,0,f,0,e9,0,e,0,e9,45,4e,44
2022-01-31 10:35:30.197  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:35:30.199  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stRealTimeData->stInverterSignalLevel
2022-01-31 10:35:30.200  - debug: apsystems-ecu.0 (742) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000054025END)
2022-01-31 10:35:31.401  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:35:31.447  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:35:31.450  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:35:31.453  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:35:33.201  - warn: apsystems-ecu.0 (742) rspWatchDog +++ timeout
2022-01-31 10:35:33.201  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseTimeout) stWaitForResponse -> stWaitForResponse
2022-01-31 10:35:33.201  - warn: apsystems-ecu.0 (742) Ecu.ecuStateMachine() - no response from Ecu, repeat service ...
2022-01-31 10:35:33.202  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stInverterSignalLevel->stInverterSignalLevel
2022-01-31 10:35:33.202  - debug: apsystems-ecu.0 (742) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000054025END)
2022-01-31 10:35:33.219  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 17ms - 41,50,53,31,31,30,30,34,36,30,30,33,30,30,30,40,80,0,8,98,23,b1,40,80,0,9,50,33,b8,40,80,0,9,47,6,b8,40,80,0,12,16,99,b4,45,4e,44
2022-01-31 10:35:33.220  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessInverterSignalLevel() - status=00 - done
2022-01-31 10:35:33.221  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:35:33.221  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stInverterSignalLevel->stEnergyOfWeek - recursion
2022-01-31 10:35:33.221  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfWeek->stEnergyOfYear - recursion
2022-01-31 10:35:33.222  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evNoNextService) stWaitForResponse -> stWaitForDisconnected
2022-01-31 10:35:33.222  - debug: apsystems-ecu.0 (742) Ecu.trDisconnect() - done
2022-01-31 10:35:33.222  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfYear->stSystemInfo
2022-01-31 10:35:33.223  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-01-31 10:35:33.223  - debug: apsystems-ecu.0 (742) Ecu.trNextCycle() 60sec delay


2022-01-31 10:36:33.226  - debug: apsystems-ecu.0 (742) Ecu.trConnect() hide=false, ip=10.0.0.178 port=8899
2022-01-31 10:36:33.230  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-01-31 10:36:33.231  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService/216000054025) stSystemInfo->stSystemInfo
2022-01-31 10:36:33.232  - debug: apsystems-ecu.0 (742) Ecu.reqService(SYSTEMINFO req:APS1100160001END)
2022-01-31 10:36:33.252  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 20ms - 41,50,53,31,31,30,30,39,34,30,30,30,31,32,31,36,30,30,30,30,35,34,30,32,35,30,31,0,0,4f,6b,0,0,0,7a,0,0,0,d,d0,d0,d0,d0,d0,d0,d0,0,4,0,4,31,30,30,31,32,45,43,55,5f,52,5f,31,2e,32,2e,31,39,30,30,39,45,74,63,2f,47,4d,54,2d,38,80,97,1b,1,cc,b2,0,0,0,0,0,0,45,4e,44
2022-01-31 10:36:33.253  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessSystemInfo() - done
2022-01-31 10:36:33.254  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:36:33.254  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stSystemInfo->stRealTimeData
2022-01-31 10:36:33.255  - debug: apsystems-ecu.0 (742) Ecu.reqService(REAL_TIME_DATA req:APS1100280002216000054025END)

2022-01-31 10:36:33.319  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 64ms - 41,50,53,31,31,30,31,31,33,30,30,30,32,30,30,30,31,0,4,20,22,1,31,10,35,42,40,80,0,8,98,23,1,30,31,1,f3,0,69,0,12,0,e9,0,10,0,e9,40,80,0,9,50,33,1,30,31,1,f3,0,69,0,11,0,e9,0,f,0,e9,40,80,0,9,47,6,1,30,31,1,f3,0,69,0,c,0,e8,0,f,0,e8,40,80,0,12,16,99,1,30,31,1,f3,0,69,0,f,0,e8,0,e,0,e8,45,4e,44

2022-01-31 10:36:33.323  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:36:33.323  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stRealTimeData->stInverterSignalLevel
2022-01-31 10:36:33.324  - debug: apsystems-ecu.0 (742) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000054025END)
2022-01-31 10:36:33.343  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 19ms - 41,50,53,31,31,30,30,34,36,30,30,33,30,30,30,40,80,0,8,98,23,b2,40,80,0,9,50,33,b8,40,80,0,9,47,6,b8,40,80,0,12,16,99,b5,45,4e,44
2022-01-31 10:36:33.344  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessInverterSignalLevel() - status=00 - done
2022-01-31 10:36:33.345  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:36:33.346  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stInverterSignalLevel->stEnergyOfWeek - recursion
2022-01-31 10:36:33.346  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfWeek->stEnergyOfYear - recursion
2022-01-31 10:36:33.347  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evNoNextService) stWaitForResponse -> stWaitForDisconnected
2022-01-31 10:36:33.347  - debug: apsystems-ecu.0 (742) Ecu.trDisconnect() - done
2022-01-31 10:36:33.347  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfYear->stSystemInfo
2022-01-31 10:36:33.348  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-01-31 10:36:33.348  - debug: apsystems-ecu.0 (742) Ecu.trNextCycle() 60sec delay
2022-01-31 10:36:34.399  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:36:34.403  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:36:34.415  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:36:34.495  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done

2022-01-31 10:37:33.350  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-01-31 10:37:33.350  - debug: apsystems-ecu.0 (742) Ecu.trConnect() hide=false, ip=10.0.0.178 port=8899
2022-01-31 10:37:33.354  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-01-31 10:37:33.355  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService/216000054025) stSystemInfo->stSystemInfo
2022-01-31 10:37:33.356  - debug: apsystems-ecu.0 (742) Ecu.reqService(SYSTEMINFO req:APS1100160001END)
2022-01-31 10:37:33.377  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 21ms - 41,50,53,31,31,30,30,39,34,30,30,30,31,32,31,36,30,30,30,30,35,34,30,32,35,30,31,0,0,4f,6b,0,0,0,7a,0,0,0,d,d0,d0,d0,d0,d0,d0,d0,0,4,0,4,31,30,30,31,32,45,43,55,5f,52,5f,31,2e,32,2e,31,39,30,30,39,45,74,63,2f,47,4d,54,2d,38,80,97,1b,1,cc,b2,0,0,0,0,0,0,45,4e,44
2022-01-31 10:37:33.378  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessSystemInfo() - done
2022-01-31 10:37:33.378  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:37:33.378  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stSystemInfo->stRealTimeData
2022-01-31 10:37:33.379  - debug: apsystems-ecu.0 (742) Ecu.reqService(REAL_TIME_DATA req:APS1100280002216000054025END)
2022-01-31 10:37:33.443  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 62ms - 41,50,53,31,31,30,31,31,33,30,30,30,32,30,30,30,31,0,4,20,22,1,31,10,35,42,40,80,0,8,98,23,1,30,31,1,f3,0,69,0,12,0,e9,0,10,0,e9,40,80,0,9,50,33,1,30,31,1,f3,0,69,0,11,0,e9,0,f,0,e9,40,80,0,9,47,6,1,30,31,1,f3,0,69,0,c,0,e8,0,f,0,e8,40,80,0,12,16,99,1,30,31,1,f3,0,69,0,f,0,e8,0,e,0,e8,45,4e,44
2022-01-31 10:37:33.450  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:37:33.451  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stRealTimeData->stInverterSignalLevel
2022-01-31 10:37:33.453  - debug: apsystems-ecu.0 (742) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000054025END)
2022-01-31 10:37:33.470  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 18ms - 41,50,53,31,31,30,30,34,36,30,30,33,30,30,30,40,80,0,8,98,23,b2,40,80,0,9,50,33,b8,40,80,0,9,47,6,b8,40,80,0,12,16,99,b5,45,4e,44
2022-01-31 10:37:33.472  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessInverterSignalLevel() - status=00 - done
2022-01-31 10:37:33.472  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:37:33.473  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stInverterSignalLevel->stEnergyOfWeek - recursion
2022-01-31 10:37:33.473  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfWeek->stEnergyOfYear - recursion
2022-01-31 10:37:33.474  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evNoNextService) stWaitForResponse -> stWaitForDisconnected
2022-01-31 10:37:33.474  - debug: apsystems-ecu.0 (742) Ecu.trDisconnect() - done
2022-01-31 10:37:33.474  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfYear->stSystemInfo
2022-01-31 10:37:33.475  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-01-31 10:37:33.475  - debug: apsystems-ecu.0 (742) Ecu.trNextCycle() 60sec delay
2022-01-31 10:37:34.495  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:37:34.508  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:37:34.579  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:37:34.582  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done

2022-01-31 10:38:33.477  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-01-31 10:38:33.477  - debug: apsystems-ecu.0 (742) Ecu.trConnect() hide=false, ip=10.0.0.178 port=8899
2022-01-31 10:38:33.482  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-01-31 10:38:33.483  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService/216000054025) stSystemInfo->stSystemInfo
2022-01-31 10:38:33.483  - debug: apsystems-ecu.0 (742) Ecu.reqService(SYSTEMINFO req:APS1100160001END)
2022-01-31 10:38:33.503  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 19ms - 41,50,53,31,31,30,30,39,34,30,30,30,31,32,31,36,30,30,30,30,35,34,30,32,35,30,31,0,0,4f,6b,0,0,0,7a,0,0,0,d,d0,d0,d0,d0,d0,d0,d0,0,4,0,4,31,30,30,31,32,45,43,55,5f,52,5f,31,2e,32,2e,31,39,30,30,39,45,74,63,2f,47,4d,54,2d,38,80,97,1b,1,cc,b2,0,0,0,0,0,0,45,4e,44
2022-01-31 10:38:33.504  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessSystemInfo() - done
2022-01-31 10:38:33.504  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:38:33.504  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stSystemInfo->stRealTimeData
2022-01-31 10:38:33.505  - debug: apsystems-ecu.0 (742) Ecu.reqService(REAL_TIME_DATA req:APS1100280002216000054025END)
2022-01-31 10:38:33.570  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 65ms - 41,50,53,31,31,30,31,31,33,30,30,30,32,30,30,30,31,0,4,20,22,1,31,10,35,42,40,80,0,8,98,23,1,30,31,1,f3,0,69,0,12,0,e9,0,10,0,e9,40,80,0,9,50,33,1,30,31,1,f3,0,69,0,11,0,e9,0,f,0,e9,40,80,0,9,47,6,1,30,31,1,f3,0,69,0,c,0,e8,0,f,0,e8,40,80,0,12,16,99,1,30,31,1,f3,0,69,0,f,0,e8,0,e,0,e8,45,4e,44
2022-01-31 10:38:33.573  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:38:33.573  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stRealTimeData->stInverterSignalLevel
2022-01-31 10:38:33.574  - debug: apsystems-ecu.0 (742) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000054025END)
2022-01-31 10:38:33.594  - debug: apsystems-ecu.0 (742) Ecu.decodeRsp() 20ms - 41,50,53,31,31,30,30,34,36,30,30,33,30,30,30,40,80,0,8,98,23,b2,40,80,0,9,50,33,b8,40,80,0,9,47,6,b8,40,80,0,12,16,99,b5,45,4e,44
2022-01-31 10:38:33.595  - debug: apsystems-ecu.0 (742) Ecu.decodeAndProcessInverterSignalLevel() - status=00 - done
2022-01-31 10:38:33.596  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForResponse
2022-01-31 10:38:33.596  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evNextService) stInverterSignalLevel->stEnergyOfWeek - recursion
2022-01-31 10:38:33.596  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfWeek->stEnergyOfYear - recursion
2022-01-31 10:38:33.597  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evNoNextService) stWaitForResponse -> stWaitForDisconnected
2022-01-31 10:38:33.597  - debug: apsystems-ecu.0 (742) Ecu.trDisconnect() - done
2022-01-31 10:38:33.598  - debug: apsystems-ecu.0 (742) Ecu.trServiceSM(evRepeatService) stEnergyOfYear->stSystemInfo
2022-01-31 10:38:33.598  - debug: apsystems-ecu.0 (742) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-01-31 10:38:33.598  - debug: apsystems-ecu.0 (742) Ecu.trNextCycle() 60sec delay
2022-01-31 10:38:34.657  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:38:34.659  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:38:34.667  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done
2022-01-31 10:38:34.711  - debug: apsystems-ecu.0 (742) ecu.createAndSetInverterObjects(yc600) - done