2022-07-26 20:44:03.658 - info: apsystems-ecu.0 (3473) starting. Version 0.2.9 (non-npm: npeter/ioBroker.apsystems-ecu#c737ed3ce633041d6adb0494830458a2676c8062) in /opt/iobroker/node_modules/iobroker.apsystems-ecu, node: v16.16.0, js-controller: 4.0.23
2022-07-26 20:44:03.735 - silly: apsystems-ecu.0 (3473) States system redis pmessage system.adapter.apsystems-ecu.0.logLevel/system.adapter.apsystems-ecu.0.logLevel:{"val":"silly","ack":true,"ts":1658861043715,"q":0,"from":"system.adapter.apsystems-ecu.0","lc":1658860958577}
2022-07-26 20:44:03.933 - debug: apsystems-ecu.0 (3473) Ecu.createStaticObjects() - done
2022-07-26 20:44:03.951 - debug: apsystems-ecu.0 (3473) sunrise at {"hour":5,"minute":14}
2022-07-26 20:44:04.098 - debug: apsystems-ecu.0 (3473) sunset at {"hour":21,"minute":8}
2022-07-26 20:44:04.111 - debug: apsystems-ecu.0 (3473) new scheduleSunSetSunrise() at Tue Jul 26 2022 20:44:04 GMT+0200 (Mitteleuropäische Sommerzeit)
2022-07-26 20:44:04.133 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_energy_of_week/apsystems-ecu.0.ecu.cmd_energy_of_week:{"val":true,"ack":false,"ts":1658861044123,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861044123}
2022-07-26 20:44:04.138 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_energy_of_week state:true ack:false
2022-07-26 20:44:04.141 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_energy_of_week changed: true (ack = false)
2022-07-26 20:44:04.155 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_energy_of_month/apsystems-ecu.0.ecu.cmd_energy_of_month:{"val":true,"ack":false,"ts":1658861044143,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861044143}
2022-07-26 20:44:04.159 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_energy_of_month state:true ack:false
2022-07-26 20:44:04.161 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_energy_of_month changed: true (ack = false)
2022-07-26 20:44:04.184 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_energy_of_year/apsystems-ecu.0.ecu.cmd_energy_of_year:{"val":true,"ack":false,"ts":1658861044181,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861044181}
2022-07-26 20:44:04.189 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_energy_of_year state:true ack:false
2022-07-26 20:44:04.191 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_energy_of_year changed: true (ack = false)
2022-07-26 20:44:04.200 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_power_of_day/apsystems-ecu.0.ecu.cmd_power_of_day:{"val":true,"ack":false,"ts":1658861044182,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658860725841}
2022-07-26 20:44:04.206 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_power_of_day state:true ack:false
2022-07-26 20:44:04.209 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_power_of_day changed: true (ack = false)
2022-07-26 20:44:04.214 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.power_of_day_date/apsystems-ecu.0.ecu.power_of_day_date:{"val":"2022-07-26","ack":true,"ts":1658861044204,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658860725856}
2022-07-26 20:44:04.220 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.power_of_day_date state:2022-07-26 ack:true
2022-07-26 20:44:04.221 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.power_of_day_date changed: 2022-07-26 (ack = true)
2022-07-26 20:44:04.224 - debug: apsystems-ecu.0 (3473) Ecu.init() extendedService=true - done
2022-07-26 20:44:04.225 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evInit) stWaitForInit -> stWaitForConnect
2022-07-26 20:44:04.228 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:44:04.241 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_start_stop/apsystems-ecu.0.ecu.cmd_start_stop:{"val":true,"ack":false,"ts":1658861044236,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658860725267}
2022-07-26 20:44:04.244 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_start_stop state:true ack:false
2022-07-26 20:44:04.247 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_start_stop changed: true (ack = false)
2022-07-26 20:44:04.258 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:44:04.261 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService/null) stSystemInfo->stSystemInfo
2022-07-26 20:44:04.265 - debug: apsystems-ecu.0 (3473) Ecu.reqService(SYSTEMINFO req:APS1100160001END)
2022-07-26 20:44:04.266 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stSystemInfo->stRealTimeData
2022-07-26 20:44:04.287 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 21ms - 41,50,53,31,31,30,30,39,34,30,30,30,31,32,31,36,30,30,30,30,30,30,30,30,30,30,31,0,0,7f,eb,0,0,0,13,0,0,2,ca,d0,d0,d0,d0,d0,d0,d0,0,5,0,4,31,30,30,31,32,45,43,55,5f,52,5f,31,2e,32,2e,32,32,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-07-26 20:44:04.289 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"11","frameLen":94,"commandNumber":"0001"}
2022-07-26 20:44:04.293 - silly: apsystems-ecu.0 (3473) SYSTEM_INFO: {"id":"216000xxxxxx","model":"01","lifeTimeEnergy":3274.7,"lastSystemPower":19,"currentDayEnergy":7.14,"lastTimeConnectedEMA":"130130130130130130130","inverters":5,"invertersOnline":4,"channel":"10","versionLen":12,"version":"ECU_R_1.2.22","timeZoneLen":9,"timeZone":"Etc/GMT-8","ethernetMac":"8097111011212112","wirelessMac":"000000000000","signatureStop":"END"}
2022-07-26 20:44:04.297 - debug: apsystems-ecu.0 (3473) Ecu.decodeAndProcessSystemInfo() - version: ECU_R_1.2.22 - done
2022-07-26 20:44:04.298 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:44:04.301 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:44:04.302 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:44:04.304 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay
2022-07-26 20:44:04.329 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_start_stop/apsystems-ecu.0.ecu.cmd_start_stop:{"val":true,"ack":true,"ts":1658861044319,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658860725267}
2022-07-26 20:44:04.333 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_start_stop state:true ack:true
2022-07-26 20:44:04.334 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_start_stop changed: true (ack = true)

2022-07-26 20:46:05.306 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-07-26 20:46:05.309 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:46:05.420 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:46:05.421 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stRealTimeData->stRealTimeData
2022-07-26 20:46:05.423 - debug: apsystems-ecu.0 (3473) Ecu.reqService(REAL_TIME_DATA req:APS1100280002216000xxxxxxEND)
2022-07-26 20:46:05.424 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stRealTimeData->stInverterSignalLevel
2022-07-26 20:46:05.485 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 62ms - 41,50,53,31,31,30,31,32,32,30,30,30,32,30,30,30,31,0,5,30,30,30,30,30,30,56,40,80,0,12,16,99,1,30,31,1,f4,0,7c,0,2,0,e7,0,2,0,e7,40,80,0,9,50,33,1,30,31,1,f4,0,7c,0,2,0,e7,0,2,0,e7,40,80,0,9,47,6,1,30,31,1,f3,0,7c,0,2,0,e6,0,3,0,e6,40,80,0,8,98,23,1,30,31,1,f3,0,7b,0,3,0,e8,0,3,0,e8,40,80,0,8,98,14,0,30,30,45,4e,44
2022-07-26 20:46:05.486 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"11","frameLen":122,"commandNumber":"0002"}
2022-07-26 20:46:05.489 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA: {"matchStatus":"00","ecuModel":"01","inverters":5,"dateTime":"2022-07-26T18:41:56.000Z"}
2022-07-26 20:46:05.489 - silly: apsystems-ecu.0 (3473) Ecu.decodeAndProcessRealTimeData() - inverter loop: 1
2022-07-26 20:46:05.493 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA_INVERTER: {"dateTime":"2022-07-26T18:41:56.000Z","inverterIdType":64,"inverterId":"408000121699","state":1,"inverterType":"01","frequency":50,"temperature":24,"power1":2,"voltage1":231,"power2":2,"voltage2":231,"dc_power":4}
2022-07-26 20:46:05.494 - silly: apsystems-ecu.0 (3473) Ecu.decodeAndProcessRealTimeData() - inverter loop: 2
2022-07-26 20:46:05.495 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA_INVERTER: {"dateTime":"2022-07-26T18:41:56.000Z","inverterIdType":64,"inverterId":"408000095033","state":1,"inverterType":"01","frequency":50,"temperature":24,"power1":2,"voltage1":231,"power2":2,"voltage2":231,"dc_power":4}
2022-07-26 20:46:05.496 - silly: apsystems-ecu.0 (3473) Ecu.decodeAndProcessRealTimeData() - inverter loop: 3
2022-07-26 20:46:05.497 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA_INVERTER: {"dateTime":"2022-07-26T18:41:56.000Z","inverterIdType":64,"inverterId":"408000094706","state":1,"inverterType":"01","frequency":49.9,"temperature":24,"power1":2,"voltage1":230,"power2":3,"voltage2":230,"dc_power":5}
2022-07-26 20:46:05.498 - silly: apsystems-ecu.0 (3473) Ecu.decodeAndProcessRealTimeData() - inverter loop: 4
2022-07-26 20:46:05.499 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA_INVERTER: {"dateTime":"2022-07-26T18:41:56.000Z","inverterIdType":64,"inverterId":"408000089823","state":1,"inverterType":"01","frequency":49.9,"temperature":23,"power1":3,"voltage1":232,"power2":3,"voltage2":232,"dc_power":6}
2022-07-26 20:46:05.499 - silly: apsystems-ecu.0 (3473) Ecu.decodeAndProcessRealTimeData() - inverter loop: 5
2022-07-26 20:46:05.500 - debug: apsystems-ecu.0 (3473) ecu.decodeAndProcessRealTimeData() only registered - 0 / 408000089814
2022-07-26 20:46:05.501 - silly: apsystems-ecu.0 (3473) REAL_TIME_DATA_INVERTER: {"dateTime":"2022-07-26T18:41:56.000Z","inverterIdType":0,"inverterId":"408000089814","state":0,"inverterType":"00"}
2022-07-26 20:46:05.502 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:46:05.503 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:46:05.504 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:46:05.505 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay
2022-07-26 20:46:05.581 - silly: apsystems-ecu.0 (3473) Ecu.createInverterObjects(false, yc600_408000121699) done
2022-07-26 20:46:05.583 - silly: apsystems-ecu.0 (3473) Ecu.createInverterQs1Objects(false, yc600_408000121699) done
2022-07-26 20:46:05.584 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc600AndDs3Objects(false, yc600_408000121699) - yc600 already done
2022-07-26 20:46:05.585 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc100Objects(false, yc600_408000121699) done
2022-07-26 20:46:05.587 - silly: apsystems-ecu.0 (3473) Ecu.createInverterObjects(false, yc600_408000095033) done
2022-07-26 20:46:05.588 - silly: apsystems-ecu.0 (3473) Ecu.createInverterQs1Objects(false, yc600_408000095033) done
2022-07-26 20:46:05.588 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc600AndDs3Objects(false, yc600_408000095033) - yc600 already done
2022-07-26 20:46:05.589 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc100Objects(false, yc600_408000095033) done
2022-07-26 20:46:05.591 - silly: apsystems-ecu.0 (3473) Ecu.createInverterObjects(false, yc600_408000094706) done
2022-07-26 20:46:05.591 - silly: apsystems-ecu.0 (3473) Ecu.createInverterQs1Objects(false, yc600_408000094706) done
2022-07-26 20:46:05.592 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc600AndDs3Objects(false, yc600_408000094706) - yc600 already done
2022-07-26 20:46:05.593 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc100Objects(false, yc600_408000094706) done
2022-07-26 20:46:05.632 - debug: apsystems-ecu.0 (3473) ecu.createAndSetInverterObjects(yc600,408000121699) - done
2022-07-26 20:46:05.642 - silly: apsystems-ecu.0 (3473) Ecu.createInverterObjects(false, yc600_408000089823) done
2022-07-26 20:46:05.644 - silly: apsystems-ecu.0 (3473) Ecu.createInverterQs1Objects(false, yc600_408000089823) done
2022-07-26 20:46:05.645 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc600AndDs3Objects(false, yc600_408000089823) - yc600 already done
2022-07-26 20:46:05.646 - silly: apsystems-ecu.0 (3473) Ecu.createInverterYc100Objects(false, yc600_408000089823) done
2022-07-26 20:46:05.667 - debug: apsystems-ecu.0 (3473) ecu.createAndSetInverterObjects(yc600,408000095033) - done
2022-07-26 20:46:05.676 - debug: apsystems-ecu.0 (3473) ecu.createAndSetInverterObjects(yc600,408000094706) - done
2022-07-26 20:46:05.725 - debug: apsystems-ecu.0 (3473) ecu.createAndSetInverterObjects(yc600,408000089823) - done

2022-07-26 20:48:06.508 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-07-26 20:48:06.510 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:48:06.583 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:48:06.584 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stInverterSignalLevel->stInverterSignalLevel
2022-07-26 20:48:06.585 - debug: apsystems-ecu.0 (3473) Ecu.reqService(INVERTER_SIGNAL_LEVEL req:APS1100280030216000xxxxxxEND)
2022-07-26 20:48:06.586 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stInverterSignalLevel->stPowerOfDay
2022-07-26 20:48:06.604 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 18ms - 41,50,53,31,31,30,30,35,33,30,30,33,30,30,30,40,80,0,12,30,30,30,30,30,30,9,50,33,b7,40,80,0,9,47,6,b8,40,80,0,8,98,23,af,40,80,0,8,98,14,0,45,4e,44
2022-07-26 20:48:06.605 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"11","frameLen":53,"commandNumber":"0030"}
2022-07-26 20:48:06.607 - debug: apsystems-ecu.0 (3473) prefix=yc600_408000121699 i=0 isl.inverterId[i]=408000121699
2022-07-26 20:48:06.608 - debug: apsystems-ecu.0 (3473) prefix=yc600_408000095033 i=1 isl.inverterId[i]=408000095033
2022-07-26 20:48:06.609 - debug: apsystems-ecu.0 (3473) prefix=yc600_408000094706 i=2 isl.inverterId[i]=408000094706
2022-07-26 20:48:06.611 - debug: apsystems-ecu.0 (3473) prefix=yc600_408000089823 i=3 isl.inverterId[i]=408000089823
2022-07-26 20:48:06.612 - debug: apsystems-ecu.0 (3473) prefix=undefined i=4 isl.inverterId[i]=408000089814
2022-07-26 20:48:06.613 - debug: apsystems-ecu.0 (3473) Ecu.decodeAndProcessInverterSignalLevel() - status=00 - done
2022-07-26 20:48:06.614 - silly: apsystems-ecu.0 (3473) INVERTER_SIGNAL_LEVEL: {"status":"00","inverterId":["408000121699","408000095033","408000094706","408000089823","408000089814"],"level":[180,183,184,175,0]}
2022-07-26 20:48:06.615 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:48:06.616 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:48:06.617 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:48:06.618 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay

2022-07-26 20:50:07.620 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-07-26 20:50:07.621 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:50:07.644 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:50:07.645 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stPowerOfDay->stPowerOfDay
2022-07-26 20:50:07.646 - debug: apsystems-ecu.0 (3473) Ecu.reqService(POWER_OF_DAY req:APS1100390003216000xxxxxxEND20220726END)
2022-07-26 20:50:07.647 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stPowerOfDay->stEnergyOfWeek
2022-07-26 20:50:08.190 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 540ms - 41,50,53,31,31,30,37,36,36,30,30,30,33,30,30,5,16,0,0,30,30,30,30,30,30,0,0,5,31,0,0,5,36,0,0,5,41,0,3,5,46,0,d,5,51,0,19,5,56,0,22,6,1,0,41,6,6,0,44,6,11,0,40,6,16,0,4f,6,21,0,65,6,26,0,83,6,31,0,7b,6,36,0,7d,6,41,0,40,6,46,0,80,6,51,0,7f,6,56,0,88,7,1,0,8a,7,6,0,a0,7,11,0,c6,7,16,0,e0,7,21,0,f8,7,26,0,f1,7,31,1,b,7,36,1,3,7,41,1,22,7,46,0,fd,7,51,0,f5,7,56,1,35,8,1,1,65,8,6,1,50,8,11,1,36,8,16,1,93,8,21,1,d5,8,26,1,9e,8,31,0,c8,8,36,1,c2,8,41,2,3b,8,46,2,d2,8,51,2,4a,8,56,1,a0,9,1,1,c1,9,6,2,53,9,11,2,4c,9,16,2,2b,9,21,1,ad,9,26,2,5f,9,31,2,f4,9,36,4,18,9,41,3,16,9,46,4,29,9,51,4,4,9,56,3,9a,10,1,5,20,10,6,4,20,10,11,5,23,10,16,4,c5,10,21,2,fd,10,26,4,ca,10,31,3,47,10,36,4,3f,10,41,3,33,10,46,3,2a,10,51,2,5c,10,56,1,50,11,1,2,ef,11,6,4,a8,11,11,3,87,11,16,2,d,11,21,2,fa,11,26,3,93,11,31,3,eb,11,36,1,b7,11,41,2,36,11,46,2,e7,11,51,7,16,11,56,6,93,12,1,5,d9,12,6,3,84,12,11,2,7e,12,16,6,65,12,21,5,4a,12,26,7,7,12,31,4,d0,12,36,6,70,12,41,6,e9,12,46,6,53,12,51,4,ed,12,56,1,36,13,1,0,b2,13,6,1,6,13,11,1,ed,13,16,3,bf,13,21,6,7,13,26,3,9a,13,31,5,c1,13,36,4,e3,13,41,5,32,13,46,3,4a,13,51,1,2a,13,56,0,9a,14,1,0,6a,14,6,0,bb,14,11,0,de,14,16,1,6,14,21,0,92,14,26,0,7d,14,31,0,81,14,36,0,84,14,41,0,7e,14,46,0,ae,14,51,0,b4,14,56,0,b6,15,1,0,cd,15,6,0,9a,15,11,0,8d,15,16,0,b3,15,21,1,d,15,26,1,a1,15,31,1,dd,15,36,2,57,15,41,3,98,15,46,3,44,15,51,2,4,15,56,2,a1,16,1,2,86,16,6,2,5c,16,11,1,a9,16,16,1,54,16,21,1,82,16,26,1,2b,16,31,1,79,16,36,1,b8,16,41,2,32,16,46,2,63,16,51,2,4a,16,56,1,e8,17,1,1,98,17,6,1,67,17,11,1,9f,17,16,1,e9,17,21,1,ce,17,26,1,e8,17,31,2,5,17,36,2,41,17,41,1,c3,17,46,1,36,17,51,1,d,17,56,1,53,18,1,1,50,18,6,1,b9,18,11,0,fa,18,16,1,2,18,21,0,aa,18,26,0,89,18,31,0,93,18,36,0,df,18,41,1,2e,18,46,0,da,18,51,0,95,18,56,0,84,19,1,0,96,19,6,0,6f,19,11,0,75,19,16,0,cb,19,21,0,d0,19,26,0,c4,19,31,0,6a,19,36,0,50,19,41,0,42,19,46,0,60,19,51,0,6b,19,56,0,4e,20,1,0,3c,20,6,0,46,20,11,0,46,20,16,0,34,20,21,0,2f,20,26,0,37,20,31,0,24,20,36,0,1a,20,41,0,13,20,46,0,d,45,4e,44
2022-07-26 20:50:08.195 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"11","frameLen":766,"commandNumber":"0003"}
2022-07-26 20:50:08.203 - debug: apsystems-ecu.0 (3473) Ecu.decodeAndProcessPowerOfDay() - status=00 - done
2022-07-26 20:50:08.204 - silly: apsystems-ecu.0 (3473) POWER_OF_DAY: {"status":"00"}
2022-07-26 20:50:08.205 - silly: apsystems-ecu.0 (3473) POWER_OF_DAY: {"05:16":0,"05:21":0,"05:26":0,"05:31":0,"05:36":0,"05:41":3,"05:46":13,"05:51":25,"05:56":34,"06:01":65,"06:06":68,"06:11":64,"06:16":79,"06:21":101,"06:26":131,"06:31":123,"06:36":125,"06:41":64,"06:46":128,"06:51":127,"06:56":136,"07:01":138,"07:06":160,"07:11":198,"07:16":224,"07:21":248,"07:26":241,"07:31":267,"07:36":259,"07:41":290,"07:46":253,"07:51":245,"07:56":309,"08:01":357,"08:06":336,"08:11":310,"08:16":403,"08:21":469,"08:26":414,"08:31":200,"08:36":450,"08:41":571,"08:46":722,"08:51":586,"08:56":416,"09:01":449,"09:06":595,"09:11":588,"09:16":555,"09:21":429,"09:26":607,"09:31":756,"09:36":1048,"09:41":790,"09:46":1065,"09:51":1028,"09:56":922,"10:01":1312,"10:06":1056,"10:11":1315,"10:16":1221,"10:21":765,"10:26":1226,"10:31":839,"10:36":1087,"10:41":819,"10:46":810,"10:51":604,"10:56":336,"11:01":751,"11:06":1192,"11:11":903,"11:16":525,"11:21":762,"11:26":915,"11:31":1003,"11:36":439,"11:41":566,"11:46":743,"11:51":1814,"11:56":1683,"12:01":1497,"12:06":900,"12:11":638,"12:16":1637,"12:21":1354,"12:26":1799,"12:31":1232,"12:36":1648,"12:41":1769,"12:46":1619,"12:51":1261,"12:56":310,"13:01":178,"13:06":262,"13:11":493,"13:16":959,"13:21":1543,"13:26":922,"13:31":1473,"13:36":1251,"13:41":1330,"13:46":842,"13:51":298,"13:56":154,"14:01":106,"14:06":187,"14:11":222,"14:16":262,"14:21":146,"14:26":125,"14:31":129,"14:36":132,"14:41":126,"14:46":174,"14:51":180,"14:56":182,"15:01":205,"15:06":154,"15:11":141,"15:16":179,"15:21":269,"15:26":417,"15:31":477,"15:36":599,"15:41":920,"15:46":836,"15:51":516,"15:56":673,"16:01":646,"16:06":604,"16:11":425,"16:16":340,"16:21":386,"16:26":299,"16:31":377,"16:36":440,"16:41":562,"16:46":611,"16:51":586,"16:56":488,"17:01":408,"17:06":359,"17:11":415,"17:16":489,"17:21":462,"17:26":488,"17:31":517,"17:36":577,"17:41":451,"17:46":310,"17:51":269,"17:56":339,"18:01":336,"18:06":441,"18:11":250,"18:16":258,"18:21":170,"18:26":137,"18:31":147,"18:36":223,"18:41":302,"18:46":218,"18:51":149,"18:56":132,"19:01":150,"19:06":111,"19:11":117,"19:16":203,"19:21":208,"19:26":196,"19:31":106,"19:36":80,"19:41":66,"19:46":96,"19:51":107,"19:56":78,"20:01":60,"20:06":70,"20:11":70,"20:16":52,"20:21":47,"20:26":55,"20:31":36,"20:36":26,"20:41":19,"20:46":13}
2022-07-26 20:50:08.206 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:50:08.207 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:50:08.209 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:50:08.210 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay
2022-07-26 20:50:08.278 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_power_of_day/apsystems-ecu.0.ecu.cmd_power_of_day:{"val":false,"ack":true,"ts":1658861408268,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861408268}
2022-07-26 20:50:08.282 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_power_of_day state:false ack:true
2022-07-26 20:50:08.283 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_power_of_day changed: false (ack = true)
2022-07-26 20:50:08.288 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.power_of_day_date/apsystems-ecu.0.ecu.power_of_day_date:{"val":"2022-07-26","ack":true,"ts":1658861408269,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658860725856}
2022-07-26 20:50:08.292 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.power_of_day_date state:2022-07-26 ack:true
2022-07-26 20:50:08.293 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.power_of_day_date changed: 2022-07-26 (ack = true)

2022-07-26 20:52:09.212 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-07-26 20:52:09.214 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:52:09.319 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:52:09.320 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stEnergyOfWeek->stEnergyOfWeek
2022-07-26 20:52:09.322 - debug: apsystems-ecu.0 (3473) Ecu.reqService(ENERGY_OF_WMY req:APS1100390004216000xxxxxxEND00END)
2022-07-26 20:52:09.322 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stEnergyOfWeek->stEnergyOfMonth
2022-07-26 20:52:09.393 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 71ms - 41,50,53,31,32,30,30,37,36,30,30,30,34,30,30,30,30,20,22,7,20,0,0,4,3d,20,22,7,21,0,0,3,49,20,22,7,22,0,0,2,d3,20,22,7,23,0,0,3,43,20,22,7,24,0,0,4,5a,20,22,7,25,0,0,4,1f,20,22,7,26,0,0,2,ca,45,4e,44
2022-07-26 20:52:09.394 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"12","frameLen":76,"commandNumber":"0004"}
2022-07-26 20:52:09.397 - silly: apsystems-ecu.0 (3473) ENERGY_OF_WMY: {"status":"00","wmy":"00"}
2022-07-26 20:52:09.397 - silly: apsystems-ecu.0 (3473) ENERGY_OF_WMY: {"2022.07.20":0,"0431.32.02-2::":18.25,"0000.03.49":82.26,"0722.00.00":7.23,"2022.07.23":0,"0343.20.22":18.28,"0000.04.51-0::":82.26,"0725.00.00":10.55,"2022.07.26":0,"0212.10.45-41:4:":174.18}
2022-07-26 20:52:09.398 - debug: apsystems-ecu.0 (3473) Ecu.decodeAndProcessEnergyOfWMY() - wmy=week - done
2022-07-26 20:52:09.399 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:52:09.400 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:52:09.400 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:52:09.401 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay
2022-07-26 20:52:09.458 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_energy_of_week/apsystems-ecu.0.ecu.cmd_energy_of_week:{"val":false,"ack":true,"ts":1658861529453,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861529453}
2022-07-26 20:52:09.461 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_energy_of_week state:false ack:true
2022-07-26 20:52:09.462 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_energy_of_week changed: false (ack = true)

2022-07-26 20:54:10.403 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evCycleTimer) stWaitForNextCycle -> stWaitForConnect
2022-07-26 20:54:10.404 - debug: apsystems-ecu.0 (3473) Ecu.trConnect() hide=true, ip=10.0.0.178 port=8899
2022-07-26 20:54:10.485 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evConnected) stWaitForConnect -> stWaitForResponse
2022-07-26 20:54:10.487 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stEnergyOfMonth->stEnergyOfMonth
2022-07-26 20:54:10.488 - debug: apsystems-ecu.0 (3473) Ecu.reqService(ENERGY_OF_WMY req:APS1100390004216000xxxxxxEND01END)
2022-07-26 20:54:10.488 - debug: apsystems-ecu.0 (3473) Ecu.trServiceSM2(evRepeatService) stEnergyOfMonth->stEnergyOfYear
2022-07-26 20:54:10.635 - debug: apsystems-ecu.0 (3473) Ecu.decodeRsp() 146ms - 41,50,53,31,32,30,32,36,30,30,30,30,34,30,30,30,31,20,22,6,27,0,0,4,60,20,22,6,28,0,0,2,8c,20,22,6,29,0,0,3,a9,20,22,6,30,0,0,2,7,20,22,7,1,0,0,2,94,20,22,7,2,0,0,4,54,20,22,7,3,0,0,4,96,20,22,7,4,0,0,4,2a,20,22,7,5,0,0,4,1b,20,22,7,6,0,0,2,d6,20,22,7,7,0,0,1,81,20,22,7,8,0,0,2,d7,20,22,7,9,0,0,2,a3,20,22,7,10,0,0,3,a2,20,22,7,11,0,0,2,d1,20,22,7,12,0,0,4,19,20,22,7,13,0,0,3,4e,20,22,7,14,0,0,4,b8,20,22,7,15,0,0,3,55,20,22,7,16,0,0,3,b2,20,22,7,17,0,0,3,86,20,22,7,18,0,0,3,c0,20,22,7,19,0,0,4,4a,20,22,7,20,0,0,4,3d,20,22,7,21,0,0,3,49,20,22,7,22,0,0,2,d3,20,22,7,23,0,0,3,43,20,22,7,24,0,0,4,5a,20,22,7,25,0,0,4,1f,20,22,7,26,0,0,2,ca,45,4e,44
2022-07-26 20:54:10.636 - silly: apsystems-ecu.0 (3473) HEADER: {"signatureStart":"APS","commandGroup":"12","frameLen":260,"commandNumber":"0004"}
2022-07-26 20:54:10.640 - silly: apsystems-ecu.0 (3473) ENERGY_OF_WMY: {"status":"00","wmy":"01"}
2022-07-26 20:54:10.641 - silly: apsystems-ecu.0 (3473) ENERGY_OF_WMY: {"2022.06.27":0,"0460.20.22":15.76,"0000.02.81-2::":82.26,"0629.00.00":9.37,"2022.06.30":0,"0207.20.22":17.93,"0000.02.94":82.26,"0702.00.00":11.08,"2022.07.03":0,"0496.20.22":17.96,"0000.04.21-0::":82.26,"0705.00.00":10.51,"2022.07.06":0,"0213.62.02-2::":17.99,"0000.01.81":82.26,"0708.00.00":7.27,"2022.07.09":0,"0210.32.02-2::":18.08,"0000.03.10-2::":82.26,"0711.00.00":7.21,"2022.07.12":0,"0419.20.22":18.11,"0000.03.41-4::":82.26,"0714.00.00":12.08,"2022.07.15":0,"0355.20.22":18.14,"0000.03.11-2::":82.26,"0717.00.00":9.02,"2022.07.18":0,"0312.02.02-2::":18.17,"0000.04.41-0::":82.26,"0720.00.00":10.85,"2022.07.21":0,"0349.20.22":18.26,"0000.02.13-3::":82.26,"0723.00.00":8.35,"2022.07.24":0,"0451.02.02-2::":18.29,"0000.04.11-5::":82.26,"0726.00.00":7.14}
2022-07-26 20:54:10.641 - debug: apsystems-ecu.0 (3473) Ecu.decodeAndProcessEnergyOfWMY() - wmy=month - done
2022-07-26 20:54:10.642 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evResponseOk) stWaitForResponse -> stWaitForDisconnected
2022-07-26 20:54:10.643 - debug: apsystems-ecu.0 (3473) Ecu.trDisconnect() - done
2022-07-26 20:54:10.644 - debug: apsystems-ecu.0 (3473) Ecu.ecuStateMachine(evDisconnected) stWaitForDisconnected -> stWaitForNextCycle
2022-07-26 20:54:10.644 - debug: apsystems-ecu.0 (3473) Ecu.trNextCycle() 121sec delay
2022-07-26 20:54:10.718 - silly: apsystems-ecu.0 (3473) States user redis pmessage apsystems-ecu.0.ecu.cmd_energy_of_month/apsystems-ecu.0.ecu.cmd_energy_of_month:{"val":false,"ack":true,"ts":1658861650711,"q":0,"from":"system.adapter.apsystems-ecu.0","user":"system.user.admin","lc":1658861650711}
2022-07-26 20:54:10.721 - silly: apsystems-ecu.0 (3473) onStateChange() - id:apsystems-ecu.0.ecu.cmd_energy_of_month state:false ack:true
2022-07-26 20:54:10.722 - info: apsystems-ecu.0 (3473) state apsystems-ecu.0.ecu.cmd_energy_of_month changed: false (ack = true)