search

iobroker-community-adapters / ioBroker.kecontact

ioBroker adapter for KEBA KeContact wallbox
Apache License 2.0
9 stars 10 forks source link

Issue when Charging Tesla Model Y with photovoltaic automatic enabled #125

Closed gogfried closed 1 year ago

gogfried commented 2 years ago

I have two electric cars (Volkswagen id3 and Tesla Model Y) and a photovoltaic system from SMA. The id3 dynamically charges without any issues with my Keba KeContact P30 and the ioBroker.kecontact adapter. The Tesla Model Y has issues and stops charging very shortly after starting. Based on the debug logs it seems the adapter is stopping the charging session. I tried debugging the behaviour and it seems it always runs into this line https://github.com/iobroker-community-adapters/ioBroker.kecontact/blob/9f4b92422eeff57634a5c9ec3a69a8d58fb0f4f8/main.js#L1124 where curr < getMinCurrent()) 

debug: kecontact.0 (24704) Available surplus: 3896
debug: kecontact.0 (24704) power: 3896 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = 5600
debug: kecontact.0 (24704) not enough power for charging ...
info: kecontact.0 (24704) stop charging

The power of the wallbox is included in the surplus. It seems the Tesla charging electronic is ramping the power/amperage up over a few seconds. Could this be some sort of race condition? It also seems the charging session is stopped before the minimum charing session time is even reached.

This behaviour happens regardless of the power that is delivered from the solar panels. It also happens when the panels deliver constantly more than 9kw.

Any idea what is happening?

Sneak-L8 commented 2 years ago

Hi Paul,

nothing obvious. When model y is charging with 3 phases, at least 4200W must be available. The log so shows less surplus that expected. The question is why? Please show your settings of adapter. Can you check value of surplus (and regard) states?

Sneak-L8 commented 2 years ago

Please also add debug logs from plugging model y up to stopping charging session.

gogfried commented 2 years ago
Here are two screenshots from my settings.

![slice1](https://user-images.githubusercontent.com/1167175/185217801-a6e75947-e6e9-40fc-8223-4e76dd644f81.png) ![slice2](https://user-images.githubusercontent.com/1167175/185217809-8ac14e2f-4a39-4679-b720-18ef1c7d20bd.png)

Here is a log (and below a second log) from start to end of a charging session. You can see how the surplus goes down, while the Tesla is ramping up the power. It seems like the adapter is basically not considering that the power of the wallbox needs to be added to the surplus to get the correct total value - if that makes sense. I thought this is what is solved with "States beinhalten die Leistung der Wallbox".

Log

``` 2022-08-17 14:40:57.829 - info: kecontact.0 (18991) (re)start charging 2022-08-17 14:40:57.845 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:40:57.850 - debug: kecontact.0 (18991) update state kecontact.0.u1 with value:0 2022-08-17 14:40:57.851 - debug: kecontact.0 (18991) update state kecontact.0.u2 with value:0 2022-08-17 14:40:57.852 - debug: kecontact.0 (18991) update state kecontact.0.u3 with value:0 2022-08-17 14:40:57.852 - debug: kecontact.0 (18991) update state kecontact.0.i1 with value:0 2022-08-17 14:40:57.852 - debug: kecontact.0 (18991) update state kecontact.0.i2 with value:0 2022-08-17 14:40:57.852 - debug: kecontact.0 (18991) update state kecontact.0.i3 with value:0 2022-08-17 14:40:57.853 - debug: kecontact.0 (18991) update state kecontact.0.p with value:0 2022-08-17 14:40:57.853 - debug: kecontact.0 (18991) update state kecontact.0.pf with value:0 2022-08-17 14:40:57.853 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4670.5 2022-08-17 14:40:57.858 - debug: kecontact.0 (18991) update state kecontact.0.eTotal with value:2240482 2022-08-17 14:40:57.859 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 14:40:57.859 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:10309 2022-08-17 14:40:57.859 - debug: kecontact.0 (18991) update state kecontact.0.statistics.surplus with value:7849 2022-08-17 14:40:58.125 - debug: kecontact.0 (18991) Sent 'currtime 11400 1' to 192.168.2.186:7090 2022-08-17 14:40:58.127 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-17 14:40:58.128 - debug: kecontact.0 (18991) Received TCH-OK :done 2022-08-17 14:40:58.136 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:40:58.425 - debug: kecontact.0 (18991) Sent 'report 100' to 192.168.2.186:7090 2022-08-17 14:40:58.427 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 163, "Curr HW": 20000, "E start": 22358115, "E pres": 46705, "started[s]": 1660729862, "ended[s]": 0, "started": "2022-08-17 09:51:02.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 10309 } ' 2022-08-17 14:40:58.427 - debug: kecontact.0 (18991) History ID received: 00 2022-08-17 14:40:58.428 - debug: kecontact.0 (18991) Unknown Session value received: Session ID=163 2022-08-17 14:40:58.428 - debug: kecontact.0 (18991) Unknown Session value received: Curr HW=20000 2022-08-17 14:40:58.428 - debug: kecontact.0 (18991) Unknown Session value received: E start=22358115 2022-08-17 14:40:58.429 - debug: kecontact.0 (18991) Unknown Session value received: E pres=46705 2022-08-17 14:40:58.429 - debug: kecontact.0 (18991) Unknown Session value received: started[s]=1660729862 2022-08-17 14:40:58.429 - debug: kecontact.0 (18991) Unknown Session value received: ended[s]=0 2022-08-17 14:40:58.429 - debug: kecontact.0 (18991) Unknown Session value received: started=2022-08-17 09:51:02.000 2022-08-17 14:40:58.429 - debug: kecontact.0 (18991) Unknown Session value received: ended=0 2022-08-17 14:40:58.430 - debug: kecontact.0 (18991) Unknown Session value received: reason=5 2022-08-17 14:40:58.430 - debug: kecontact.0 (18991) Unknown Session value received: timeQ=0 2022-08-17 14:40:58.430 - debug: kecontact.0 (18991) Unknown Session value received: RFID tag=0000000000000000 2022-08-17 14:40:58.430 - debug: kecontact.0 (18991) Unknown Session value received: RFID class=00000000000000000000 2022-08-17 14:40:58.430 - debug: kecontact.0 (18991) Unknown Session value received: Serial=20894742 2022-08-17 14:40:58.431 - debug: kecontact.0 (18991) Unknown Session value received: Sec=10309 2022-08-17 14:40:58.444 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:40:59.212 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-17 14:40:59.214 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-17 14:40:59.221 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:40:59.225 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 14:40:59.225 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:40:59.225 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 14:40:59.405 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 14:40:59.406 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:7597 2022-08-17 14:41:01.415 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:01.415 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:7688 2022-08-17 14:41:01.535 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"Max curr": 11400}' 2022-08-17 14:41:01.537 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 11400}' 2022-08-17 14:41:01.542 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:01.545 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:11400 2022-08-17 14:41:01.548 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:01.548 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:11400 2022-08-17 14:41:03.391 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:03.391 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:8135 2022-08-17 14:41:04.885 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-17 14:41:04.887 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-17 14:41:04.900 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:04.900 - debug: kecontact.0 (18991) update state kecontact.0.state with value:2 2022-08-17 14:41:04.901 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:04.901 - debug: kecontact.0 (18991) update state kecontact.0.state with value:2 2022-08-17 14:41:05.410 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:05.411 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:8631 2022-08-17 14:41:05.790 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-17 14:41:05.791 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-17 14:41:05.795 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:05.797 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 14:41:05.797 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:05.797 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 14:41:07.404 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 14:41:07.404 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:9106 2022-08-17 14:41:09.410 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 14:41:09.411 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:9284 2022-08-17 14:41:11.391 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:11.392 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:9061 2022-08-17 14:41:12.525 - debug: kecontact.0 (18991) Sent 'report 2' to 192.168.2.186:7090 2022-08-17 14:41:12.527 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 11400, "Max curr %": 190, "Curr HW": 20000, "Curr user": 11400, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 11400, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 10323 } ' 2022-08-17 14:41:12.555 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:12.556 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 14:41:12.556 - debug: kecontact.0 (18991) update state kecontact.0.error1 with value:0 2022-08-17 14:41:12.556 - debug: kecontact.0 (18991) update state kecontact.0.error2 with value:0 2022-08-17 14:41:12.557 - debug: kecontact.0 (18991) update state kecontact.0.plug with value:7 2022-08-17 14:41:12.557 - debug: kecontact.0 (18991) update state kecontact.0.authON with value:false 2022-08-17 14:41:12.563 - debug: kecontact.0 (18991) update state kecontact.0.authreq with value:false 2022-08-17 14:41:12.564 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 14:41:12.564 - debug: kecontact.0 (18991) update state kecontact.0.enableUser with value:true 2022-08-17 14:41:12.564 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:11400 2022-08-17 14:41:12.564 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrentPercent with value:19 2022-08-17 14:41:12.564 - debug: kecontact.0 (18991) update state kecontact.0.currentHardware with value:20000 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.currentUser with value:11400 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.currentFailsafe with value:0 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.timeoutFailsafe with value:0 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.currentTimer with value:11400 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-17 14:41:12.565 - debug: kecontact.0 (18991) update state kecontact.0.setenergy with value:0 2022-08-17 14:41:12.566 - debug: kecontact.0 (18991) update state kecontact.0.output with value:false 2022-08-17 14:41:12.566 - debug: kecontact.0 (18991) update state kecontact.0.input with value:false 2022-08-17 14:41:12.569 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 14:41:12.571 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:10323 2022-08-17 14:41:12.826 - debug: kecontact.0 (18991) Sent 'report 3' to 192.168.2.186:7090 2022-08-17 14:41:12.827 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 243, "U2": 242, "U3": 243, "I1": 659, "I2": 284, "I3": 287, "P": 21920, "PF": 76, "E pres": 46705, "E total": 22404820, "Serial": "20894742", "Sec": 10324 } ' 2022-08-17 14:41:12.828 - debug: kecontact.0 (18991) Available surplus: 9061 2022-08-17 14:41:12.846 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:12.850 - debug: kecontact.0 (18991) update state kecontact.0.u1 with value:243 2022-08-17 14:41:12.851 - debug: kecontact.0 (18991) update state kecontact.0.u2 with value:242 2022-08-17 14:41:12.851 - debug: kecontact.0 (18991) update state kecontact.0.u3 with value:243 2022-08-17 14:41:12.852 - debug: kecontact.0 (18991) update state kecontact.0.i1 with value:659 2022-08-17 14:41:12.852 - debug: kecontact.0 (18991) update state kecontact.0.i2 with value:284 2022-08-17 14:41:12.852 - debug: kecontact.0 (18991) update state kecontact.0.i3 with value:287 2022-08-17 14:41:12.853 - debug: kecontact.0 (18991) update state kecontact.0.p with value:21920 2022-08-17 14:41:12.853 - debug: kecontact.0 (18991) update state kecontact.0.pf with value:7.6 2022-08-17 14:41:12.858 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4670.5 2022-08-17 14:41:12.859 - debug: kecontact.0 (18991) update state kecontact.0.eTotal with value:2240482 2022-08-17 14:41:12.859 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 14:41:12.859 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:10324 2022-08-17 14:41:12.860 - debug: kecontact.0 (18991) update state kecontact.0.statistics.surplus with value:9061 2022-08-17 14:41:13.126 - debug: kecontact.0 (18991) Sent 'report 100' to 192.168.2.186:7090 2022-08-17 14:41:13.127 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 163, "Curr HW": 20000, "E start": 22358115, "E pres": 46705, "started[s]": 1660729862, "ended[s]": 0, "started": "2022-08-17 09:51:02.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 10324 } ' 2022-08-17 14:41:13.128 - debug: kecontact.0 (18991) History ID received: 00 2022-08-17 14:41:13.128 - debug: kecontact.0 (18991) Unknown Session value received: Session ID=163 2022-08-17 14:41:13.128 - debug: kecontact.0 (18991) Unknown Session value received: Curr HW=20000 2022-08-17 14:41:13.129 - debug: kecontact.0 (18991) Unknown Session value received: E start=22358115 2022-08-17 14:41:13.129 - debug: kecontact.0 (18991) Unknown Session value received: E pres=46705 2022-08-17 14:41:13.129 - debug: kecontact.0 (18991) Unknown Session value received: started[s]=1660729862 2022-08-17 14:41:13.129 - debug: kecontact.0 (18991) Unknown Session value received: ended[s]=0 2022-08-17 14:41:13.129 - debug: kecontact.0 (18991) Unknown Session value received: started=2022-08-17 09:51:02.000 2022-08-17 14:41:13.130 - debug: kecontact.0 (18991) Unknown Session value received: ended=0 2022-08-17 14:41:13.130 - debug: kecontact.0 (18991) Unknown Session value received: reason=5 2022-08-17 14:41:13.130 - debug: kecontact.0 (18991) Unknown Session value received: timeQ=0 2022-08-17 14:41:13.130 - debug: kecontact.0 (18991) Unknown Session value received: RFID tag=0000000000000000 2022-08-17 14:41:13.130 - debug: kecontact.0 (18991) Unknown Session value received: RFID class=00000000000000000000 2022-08-17 14:41:13.131 - debug: kecontact.0 (18991) Unknown Session value received: Serial=20894742 2022-08-17 14:41:13.131 - debug: kecontact.0 (18991) Unknown Session value received: Sec=10324 2022-08-17 14:41:13.142 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:13.400 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 14:41:13.402 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:8355 2022-08-17 14:41:15.398 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:15.400 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:7057 2022-08-17 14:41:17.403 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:17.405 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5982 2022-08-17 14:41:19.401 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:19.401 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5568 2022-08-17 14:41:21.403 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:21.404 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:4310 2022-08-17 14:41:23.406 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 14:41:23.406 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:3834 2022-08-17 14:41:25.406 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:25.407 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:3256 2022-08-17 14:41:26.407 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"E pres": 46786}' 2022-08-17 14:41:26.408 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 46786}' 2022-08-17 14:41:26.418 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:26.418 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4678.6 2022-08-17 14:41:26.421 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:26.421 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4678.6 2022-08-17 14:41:27.403 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 14:41:27.404 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:3372 2022-08-17 14:41:27.526 - debug: kecontact.0 (18991) Sent 'report 2' to 192.168.2.186:7090 2022-08-17 14:41:27.528 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 11400, "Max curr %": 190, "Curr HW": 20000, "Curr user": 11400, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 11400, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 10338 } ' 2022-08-17 14:41:27.555 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 14:41:27.558 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 14:41:27.559 - debug: kecontact.0 (18991) update state kecontact.0.error1 with value:0 2022-08-17 14:41:27.559 - debug: kecontact.0 (18991) update state kecontact.0.error2 with value:0 2022-08-17 14:41:27.560 - debug: kecontact.0 (18991) update state kecontact.0.plug with value:7 2022-08-17 14:41:27.560 - debug: kecontact.0 (18991) update state kecontact.0.authON with value:false 2022-08-17 14:41:27.560 - debug: kecontact.0 (18991) update state kecontact.0.authreq with value:false 2022-08-17 14:41:27.560 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 14:41:27.568 - debug: kecontact.0 (18991) update state kecontact.0.enableUser with value:true 2022-08-17 14:41:27.569 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:11400 2022-08-17 14:41:27.569 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrentPercent with value:19 2022-08-17 14:41:27.570 - debug: kecontact.0 (18991) update state kecontact.0.currentHardware with value:20000 2022-08-17 14:41:27.570 - debug: kecontact.0 (18991) update state kecontact.0.currentUser with value:11400 2022-08-17 14:41:27.570 - debug: kecontact.0 (18991) update state kecontact.0.currentFailsafe with value:0 2022-08-17 14:41:27.570 - debug: kecontact.0 (18991) update state kecontact.0.timeoutFailsafe with value:0 2022-08-17 14:41:27.571 - debug: kecontact.0 (18991) update state kecontact.0.currentTimer with value:11400 2022-08-17 14:41:27.571 - debug: kecontact.0 (18991) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-17 14:41:27.571 - debug: kecontact.0 (18991) update state kecontact.0.setenergy with value:0 2022-08-17 14:41:27.576 - debug: kecontact.0 (18991) update state kecontact.0.output with value:false 2022-08-17 14:41:27.576 - debug: kecontact.0 (18991) update state kecontact.0.input with value:false 2022-08-17 14:41:27.577 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 14:41:27.577 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:10338 2022-08-17 14:41:27.827 - debug: kecontact.0 (18991) Sent 'report 3' to 192.168.2.186:7090 2022-08-17 14:41:27.829 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 239, "U2": 239, "U3": 244, "I1": 10514, "I2": 10526, "I3": 8960, "P": 7334972, "PF": 968, "E pres": 46805, "E total": 22404920, "Serial": "20894742", "Sec": 10339 } ' 2022-08-17 14:41:27.829 - debug: kecontact.0 (18991) Available surplus: 3393.92 2022-08-17 14:41:27.829 - debug: kecontact.0 (18991) power: 3393.92 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = 4900 2022-08-17 14:41:27.829 - debug: kecontact.0 (18991) not enough power for charging ... 2022-08-17 14:41:27.830 - info: kecontact.0 (18991) stop charging ```

Here here is a second one from start to end of a charging session.

``` 2022-08-17 13:19:57.726 - info: kecontact.0 (18991) (re)start charging 2022-08-17 13:19:57.741 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:19:57.745 - debug: kecontact.0 (18991) update state kecontact.0.u1 with value:0 2022-08-17 13:19:57.746 - debug: kecontact.0 (18991) update state kecontact.0.u2 with value:0 2022-08-17 13:19:57.746 - debug: kecontact.0 (18991) update state kecontact.0.u3 with value:0 2022-08-17 13:19:57.746 - debug: kecontact.0 (18991) update state kecontact.0.i1 with value:0 2022-08-17 13:19:57.746 - debug: kecontact.0 (18991) update state kecontact.0.i2 with value:0 2022-08-17 13:19:57.747 - debug: kecontact.0 (18991) update state kecontact.0.i3 with value:0 2022-08-17 13:19:57.747 - debug: kecontact.0 (18991) update state kecontact.0.p with value:0 2022-08-17 13:19:57.747 - debug: kecontact.0 (18991) update state kecontact.0.pf with value:0 2022-08-17 13:19:57.751 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4627 2022-08-17 13:19:57.751 - debug: kecontact.0 (18991) update state kecontact.0.eTotal with value:2240438.5 2022-08-17 13:19:57.752 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 13:19:57.752 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:5449 2022-08-17 13:19:57.752 - debug: kecontact.0 (18991) update state kecontact.0.statistics.surplus with value:6487 2022-08-17 13:19:58.024 - debug: kecontact.0 (18991) Sent 'currtime 9400 1' to 192.168.2.186:7090 2022-08-17 13:19:58.025 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-17 13:19:58.026 - debug: kecontact.0 (18991) Received TCH-OK :done 2022-08-17 13:19:58.033 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:19:58.325 - debug: kecontact.0 (18991) Sent 'report 100' to 192.168.2.186:7090 2022-08-17 13:19:58.327 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 163, "Curr HW": 20000, "E start": 22358115, "E pres": 46270, "started[s]": 1660729862, "ended[s]": 0, "started": "2022-08-17 09:51:02.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 5449 } ' 2022-08-17 13:19:58.328 - debug: kecontact.0 (18991) History ID received: 00 2022-08-17 13:19:58.328 - debug: kecontact.0 (18991) Unknown Session value received: Session ID=163 2022-08-17 13:19:58.328 - debug: kecontact.0 (18991) Unknown Session value received: Curr HW=20000 2022-08-17 13:19:58.329 - debug: kecontact.0 (18991) Unknown Session value received: E start=22358115 2022-08-17 13:19:58.329 - debug: kecontact.0 (18991) Unknown Session value received: E pres=46270 2022-08-17 13:19:58.329 - debug: kecontact.0 (18991) Unknown Session value received: started[s]=1660729862 2022-08-17 13:19:58.329 - debug: kecontact.0 (18991) Unknown Session value received: ended[s]=0 2022-08-17 13:19:58.330 - debug: kecontact.0 (18991) Unknown Session value received: started=2022-08-17 09:51:02.000 2022-08-17 13:19:58.330 - debug: kecontact.0 (18991) Unknown Session value received: ended=0 2022-08-17 13:19:58.330 - debug: kecontact.0 (18991) Unknown Session value received: reason=5 2022-08-17 13:19:58.330 - debug: kecontact.0 (18991) Unknown Session value received: timeQ=0 2022-08-17 13:19:58.331 - debug: kecontact.0 (18991) Unknown Session value received: RFID tag=0000000000000000 2022-08-17 13:19:58.331 - debug: kecontact.0 (18991) Unknown Session value received: RFID class=00000000000000000000 2022-08-17 13:19:58.331 - debug: kecontact.0 (18991) Unknown Session value received: Serial=20894742 2022-08-17 13:19:58.331 - debug: kecontact.0 (18991) Unknown Session value received: Sec=5449 2022-08-17 13:19:58.348 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:19:58.648 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:19:58.648 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:6298 2022-08-17 13:19:59.032 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-17 13:19:59.034 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-17 13:19:59.041 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:19:59.043 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 13:19:59.046 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:19:59.047 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 13:20:00.652 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:00.654 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5619 2022-08-17 13:20:01.433 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"Max curr": 9400}' 2022-08-17 13:20:01.435 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 9400}' 2022-08-17 13:20:01.443 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:01.446 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:9400 2022-08-17 13:20:01.449 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:01.450 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:9400 2022-08-17 13:20:02.665 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:02.666 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:4894 2022-08-17 13:20:04.783 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-17 13:20:04.785 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-17 13:20:04.792 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:04.794 - debug: kecontact.0 (18991) update state kecontact.0.state with value:2 2022-08-17 13:20:04.797 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:04.798 - debug: kecontact.0 (18991) update state kecontact.0.state with value:2 2022-08-17 13:20:05.201 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-17 13:20:05.202 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5372 2022-08-17 13:20:05.688 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-17 13:20:05.690 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-17 13:20:05.696 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:05.698 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 13:20:05.700 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:05.702 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 13:20:06.653 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:06.654 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5798 2022-08-17 13:20:08.665 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:08.665 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:5965 2022-08-17 13:20:10.667 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:10.667 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:6146 2022-08-17 13:20:12.422 - debug: kecontact.0 (18991) Sent 'report 2' to 192.168.2.186:7090 2022-08-17 13:20:12.424 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 9400, "Max curr %": 156, "Curr HW": 20000, "Curr user": 9400, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 9400, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 5463 } ' 2022-08-17 13:20:12.434 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:12.437 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 13:20:12.437 - debug: kecontact.0 (18991) update state kecontact.0.error1 with value:0 2022-08-17 13:20:12.437 - debug: kecontact.0 (18991) update state kecontact.0.error2 with value:0 2022-08-17 13:20:12.437 - debug: kecontact.0 (18991) update state kecontact.0.plug with value:7 2022-08-17 13:20:12.437 - debug: kecontact.0 (18991) update state kecontact.0.authON with value:false 2022-08-17 13:20:12.438 - debug: kecontact.0 (18991) update state kecontact.0.authreq with value:false 2022-08-17 13:20:12.438 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 13:20:12.438 - debug: kecontact.0 (18991) update state kecontact.0.enableUser with value:true 2022-08-17 13:20:12.438 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:9400 2022-08-17 13:20:12.441 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrentPercent with value:15.6 2022-08-17 13:20:12.442 - debug: kecontact.0 (18991) update state kecontact.0.currentHardware with value:20000 2022-08-17 13:20:12.443 - debug: kecontact.0 (18991) update state kecontact.0.currentUser with value:9400 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.currentFailsafe with value:0 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.timeoutFailsafe with value:0 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.currentTimer with value:9400 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.setenergy with value:0 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.output with value:false 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.input with value:false 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 13:20:12.444 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:5463 2022-08-17 13:20:12.638 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:12.638 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:6374 2022-08-17 13:20:12.721 - debug: kecontact.0 (18991) Sent 'report 3' to 192.168.2.186:7090 2022-08-17 13:20:12.722 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 237, "U2": 236, "U3": 240, "I1": 667, "I2": 276, "I3": 283, "P": 21727, "PF": 77, "E pres": 46270, "E total": 22404385, "Serial": "20894742", "Sec": 5464 } ' 2022-08-17 13:20:12.723 - debug: kecontact.0 (18991) Available surplus: 6374 2022-08-17 13:20:12.730 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:12.732 - debug: kecontact.0 (18991) update state kecontact.0.u1 with value:237 2022-08-17 13:20:12.733 - debug: kecontact.0 (18991) update state kecontact.0.u2 with value:236 2022-08-17 13:20:12.733 - debug: kecontact.0 (18991) update state kecontact.0.u3 with value:240 2022-08-17 13:20:12.733 - debug: kecontact.0 (18991) update state kecontact.0.i1 with value:667 2022-08-17 13:20:12.733 - debug: kecontact.0 (18991) update state kecontact.0.i2 with value:276 2022-08-17 13:20:12.733 - debug: kecontact.0 (18991) update state kecontact.0.i3 with value:283 2022-08-17 13:20:12.734 - debug: kecontact.0 (18991) update state kecontact.0.p with value:21727 2022-08-17 13:20:12.734 - debug: kecontact.0 (18991) update state kecontact.0.pf with value:7.7 2022-08-17 13:20:12.736 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4627 2022-08-17 13:20:12.737 - debug: kecontact.0 (18991) update state kecontact.0.eTotal with value:2240438.5 2022-08-17 13:20:12.737 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 13:20:12.737 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:5464 2022-08-17 13:20:12.737 - debug: kecontact.0 (18991) update state kecontact.0.statistics.surplus with value:6374 2022-08-17 13:20:13.022 - debug: kecontact.0 (18991) Sent 'report 100' to 192.168.2.186:7090 2022-08-17 13:20:13.023 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 163, "Curr HW": 20000, "E start": 22358115, "E pres": 46270, "started[s]": 1660729862, "ended[s]": 0, "started": "2022-08-17 09:51:02.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 5464 } ' 2022-08-17 13:20:13.023 - debug: kecontact.0 (18991) History ID received: 00 2022-08-17 13:20:13.023 - debug: kecontact.0 (18991) Unknown Session value received: Session ID=163 2022-08-17 13:20:13.023 - debug: kecontact.0 (18991) Unknown Session value received: Curr HW=20000 2022-08-17 13:20:13.023 - debug: kecontact.0 (18991) Unknown Session value received: E start=22358115 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: E pres=46270 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: started[s]=1660729862 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: ended[s]=0 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: started=2022-08-17 09:51:02.000 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: ended=0 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: reason=5 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: timeQ=0 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: RFID tag=0000000000000000 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: RFID class=00000000000000000000 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: Serial=20894742 2022-08-17 13:20:13.024 - debug: kecontact.0 (18991) Unknown Session value received: Sec=5464 2022-08-17 13:20:13.030 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:14.654 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:14.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:6075 2022-08-17 13:20:16.641 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:16.642 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:4756 2022-08-17 13:20:18.658 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:18.658 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:3192 2022-08-17 13:20:20.034 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{"E pres": 46285}' 2022-08-17 13:20:20.036 - debug: kecontact.0 (18991) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 46285}' 2022-08-17 13:20:20.043 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:20.044 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4628.5 2022-08-17 13:20:20.046 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:20.046 - debug: kecontact.0 (18991) update state kecontact.0.ePres with value:4628.5 2022-08-17 13:20:20.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-17 13:20:20.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:1150 2022-08-17 13:20:22.654 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:167 2022-08-17 13:20:22.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-17 13:20:24.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:1313 2022-08-17 13:20:24.655 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-17 13:20:26.657 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.regard with value:1663.0000000000002 2022-08-17 13:20:26.658 - debug: kecontact.0 (18991) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-17 13:20:27.422 - debug: kecontact.0 (18991) Sent 'report 2' to 192.168.2.186:7090 2022-08-17 13:20:27.424 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 9400, "Max curr %": 156, "Curr HW": 20000, "Curr user": 9400, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 9400, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 5478 } ' 2022-08-17 13:20:27.453 - debug: kecontact.0 (18991) update state kecontact.0.info.connection with value:true 2022-08-17 13:20:27.455 - debug: kecontact.0 (18991) update state kecontact.0.state with value:3 2022-08-17 13:20:27.459 - debug: kecontact.0 (18991) update state kecontact.0.error1 with value:0 2022-08-17 13:20:27.459 - debug: kecontact.0 (18991) update state kecontact.0.error2 with value:0 2022-08-17 13:20:27.460 - debug: kecontact.0 (18991) update state kecontact.0.plug with value:7 2022-08-17 13:20:27.460 - debug: kecontact.0 (18991) update state kecontact.0.authON with value:false 2022-08-17 13:20:27.460 - debug: kecontact.0 (18991) update state kecontact.0.authreq with value:false 2022-08-17 13:20:27.460 - debug: kecontact.0 (18991) update state kecontact.0.enableSys with value:true 2022-08-17 13:20:27.461 - debug: kecontact.0 (18991) update state kecontact.0.enableUser with value:true 2022-08-17 13:20:27.467 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrent with value:9400 2022-08-17 13:20:27.467 - debug: kecontact.0 (18991) update state kecontact.0.maxCurrentPercent with value:15.6 2022-08-17 13:20:27.468 - debug: kecontact.0 (18991) update state kecontact.0.currentHardware with value:20000 2022-08-17 13:20:27.468 - debug: kecontact.0 (18991) update state kecontact.0.currentUser with value:9400 2022-08-17 13:20:27.468 - debug: kecontact.0 (18991) update state kecontact.0.currentFailsafe with value:0 2022-08-17 13:20:27.468 - debug: kecontact.0 (18991) update state kecontact.0.timeoutFailsafe with value:0 2022-08-17 13:20:27.469 - debug: kecontact.0 (18991) update state kecontact.0.currentTimer with value:9400 2022-08-17 13:20:27.469 - debug: kecontact.0 (18991) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-17 13:20:27.469 - debug: kecontact.0 (18991) update state kecontact.0.setenergy with value:0 2022-08-17 13:20:27.469 - debug: kecontact.0 (18991) update state kecontact.0.output with value:false 2022-08-17 13:20:27.470 - debug: kecontact.0 (18991) update state kecontact.0.input with value:false 2022-08-17 13:20:27.470 - debug: kecontact.0 (18991) update state kecontact.0.serial with value:20894742 2022-08-17 13:20:27.470 - debug: kecontact.0 (18991) update state kecontact.0.uptime with value:5478 2022-08-17 13:20:27.723 - debug: kecontact.0 (18991) Sent 'report 3' to 192.168.2.186:7090 2022-08-17 13:20:27.725 - debug: kecontact.0 (18991) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 233, "U2": 233, "U3": 239, "I1": 9085, "I2": 9063, "I3": 7310, "P": 5960993, "PF": 962, "E pres": 46361, "E total": 22404476, "Serial": "20894742", "Sec": 5479 } ' 2022-08-17 13:20:27.725 - debug: kecontact.0 (18991) Available surplus: -1641.2730000000001 2022-08-17 13:20:27.725 - debug: kecontact.0 (18991) power: -1641.2730000000001 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = -2400 2022-08-17 13:20:27.725 - debug: kecontact.0 (18991) not enough power for charging ... 2022-08-17 13:20:27.725 - info: kecontact.0 (18991) stop charging ```

Sneak-L8 commented 2 years ago

Seems that p value of Keba is not updated while vehicle starts consuming more and more power. I have to check at home how often p should be updated... Can you compare values with your other car?

gogfried commented 2 years ago

Thanks a lot for checking! Will provide a log from the other car as soon as the sun is shining again tomorrow :)

gogfried commented 2 years ago

Hi @Sneak-L8, below is a logfile from the id3 that doesn't have any issues with dynamic charging.

One (maybe irrelevant thing) I noticed is that both cars trigger the log message (re)start charging but the message vehicle (re)starts to charge only ever appears with the id3 and never shows up when the Tesla is charing. This would mean that this line is never executed when the Tesla charges: https://github.com/iobroker-community-adapters/ioBroker.kecontact/blob/5e5529ea542686b4f0c2e7a000322f0f516dcf69/main.js#L1022

Here is the log:

``` 2022-08-21 14:14:18.716 - info: kecontact.0 (6953) (re)start charging 2022-08-21 14:14:18.734 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:18.737 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:0 2022-08-21 14:14:18.738 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:0 2022-08-21 14:14:18.738 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:0 2022-08-21 14:14:18.739 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:0 2022-08-21 14:14:18.739 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:0 2022-08-21 14:14:18.740 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:0 2022-08-21 14:14:18.740 - debug: kecontact.0 (6953) update state kecontact.0.p with value:0 2022-08-21 14:14:18.740 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:0 2022-08-21 14:14:18.744 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:8390.6 2022-08-21 14:14:18.745 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2251965.5 2022-08-21 14:14:18.745 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:18.746 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354305 2022-08-21 14:14:18.746 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:8961 2022-08-21 14:14:19.011 - debug: kecontact.0 (6953) Sent 'currtime 13000 1' to 192.168.2.186:7090 2022-08-21 14:14:19.013 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-21 14:14:19.013 - debug: kecontact.0 (6953) Received TCH-OK :done 2022-08-21 14:14:19.024 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:19.311 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:14:19.313 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 164, "Curr HW": 20000, "E start": 22435749, "E pres": 83906, "started[s]": 1661079903, "ended[s]": 1661084050, "started": "2022-08-21 11:05:03.000", "ended": "2022-08-21 12:14:10.000", "reason": 1, "timeQ": 3, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354306 } ' 2022-08-21 14:14:19.313 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:14:19.314 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=164 2022-08-21 14:14:19.314 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:14:19.314 - debug: kecontact.0 (6953) Unknown Session value received: E start=22435749 2022-08-21 14:14:19.314 - debug: kecontact.0 (6953) Unknown Session value received: E pres=83906 2022-08-21 14:14:19.314 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661079903 2022-08-21 14:14:19.315 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=1661084050 2022-08-21 14:14:19.315 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 11:05:03.000 2022-08-21 14:14:19.315 - debug: kecontact.0 (6953) Unknown Session value received: ended=2022-08-21 12:14:10.000 2022-08-21 14:14:19.316 - debug: kecontact.0 (6953) Unknown Session value received: reason=1 2022-08-21 14:14:19.317 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=3 2022-08-21 14:14:19.317 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:14:19.317 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:14:19.317 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:14:19.317 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354306 2022-08-21 14:14:19.325 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:20.059 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-21 14:14:20.060 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Enable sys": 1}' 2022-08-21 14:14:20.067 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:20.070 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:14:20.071 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:20.071 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:14:20.660 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:20.661 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:8948 2022-08-21 14:14:21.213 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Plug": 7}' 2022-08-21 14:14:21.214 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Plug": 7}' 2022-08-21 14:14:21.223 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:21.228 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:14:21.229 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:21.229 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:14:22.654 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Max curr": 13000}' 2022-08-21 14:14:22.655 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 13000}' 2022-08-21 14:14:22.669 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:22.669 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:14:22.670 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:22.670 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:14:22.684 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:22.685 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:8963 2022-08-21 14:14:24.677 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:24.678 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:8954 2022-08-21 14:14:26.028 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-21 14:14:26.030 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 2}' 2022-08-21 14:14:26.038 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:26.043 - debug: kecontact.0 (6953) update state kecontact.0.state with value:2 2022-08-21 14:14:26.044 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:26.045 - debug: kecontact.0 (6953) update state kecontact.0.state with value:2 2022-08-21 14:14:26.670 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:26.671 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:8941 2022-08-21 14:14:27.133 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-21 14:14:27.135 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"State": 3}' 2022-08-21 14:14:27.143 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:27.145 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:14:27.149 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:27.151 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:14:28.673 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:28.673 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:3190 2022-08-21 14:14:29.417 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:14:29.419 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13000, "Max curr %": 216, "Curr HW": 20000, "Curr user": 13000, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13000, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354316 } ' 2022-08-21 14:14:29.443 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:29.449 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:14:29.450 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:14:29.450 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:14:29.451 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:14:29.451 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:14:29.451 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:14:29.452 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:14:29.452 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:14:29.460 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:14:29.461 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.6 2022-08-21 14:14:29.461 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:14:29.462 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13000 2022-08-21 14:14:29.462 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:14:29.462 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:14:29.463 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13000 2022-08-21 14:14:29.463 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:14:29.463 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:14:29.464 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:14:29.464 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:14:29.464 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:29.465 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354316 2022-08-21 14:14:29.717 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:14:29.718 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 238, "U2": 239, "U3": 241, "I1": 12475, "I2": 12520, "I3": 12551, "P": 8994175, "PF": 998, "E pres": 26, "E total": 22519681, "Serial": "20894742", "Sec": 354316 } ' 2022-08-21 14:14:29.719 - debug: kecontact.0 (6953) Available surplus: 3190 2022-08-21 14:14:29.727 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:29.731 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:238 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:241 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12475 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12520 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12551 2022-08-21 14:14:29.732 - debug: kecontact.0 (6953) update state kecontact.0.p with value:8994175 2022-08-21 14:14:29.733 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.8 2022-08-21 14:14:29.733 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:2.6 2022-08-21 14:14:29.733 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2251968.1 2022-08-21 14:14:29.733 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:29.735 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354316 2022-08-21 14:14:29.735 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:3190 2022-08-21 14:14:30.017 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:14:30.019 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 36, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354316 } ' 2022-08-21 14:14:30.019 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: E pres=36 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:14:30.020 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:14:30.021 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354316 2022-08-21 14:14:30.028 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:30.410 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 46}' 2022-08-21 14:14:30.412 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 46}' 2022-08-21 14:14:30.418 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:30.420 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:4.6 2022-08-21 14:14:30.423 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:30.425 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:4.6 2022-08-21 14:14:30.670 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:58 2022-08-21 14:14:30.671 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:32.668 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:63 2022-08-21 14:14:32.669 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:34.381 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 145}' 2022-08-21 14:14:34.384 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 145}' 2022-08-21 14:14:34.392 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:34.395 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:14.5 2022-08-21 14:14:34.398 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:34.401 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:14.5 2022-08-21 14:14:34.671 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:12 2022-08-21 14:14:34.673 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:36.674 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:36.676 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:6 2022-08-21 14:14:38.352 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 245}' 2022-08-21 14:14:38.354 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 245}' 2022-08-21 14:14:38.366 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:38.370 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:24.5 2022-08-21 14:14:38.371 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:38.371 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:24.5 2022-08-21 14:14:38.669 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:60 2022-08-21 14:14:38.669 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:40.677 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:25 2022-08-21 14:14:40.678 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:42.533 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 349}' 2022-08-21 14:14:42.535 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 349}' 2022-08-21 14:14:42.543 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:42.546 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:34.9 2022-08-21 14:14:42.548 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:42.550 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:34.9 2022-08-21 14:14:42.690 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:42 2022-08-21 14:14:42.691 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:44.418 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:14:44.420 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13000, "Max curr %": 216, "Curr HW": 20000, "Curr user": 13000, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13000, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354331 } ' 2022-08-21 14:14:44.442 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:44.451 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:14:44.451 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:14:44.452 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:14:44.452 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:14:44.452 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:14:44.453 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:14:44.453 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:14:44.453 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:14:44.453 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:14:44.457 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.6 2022-08-21 14:14:44.458 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:14:44.458 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13000 2022-08-21 14:14:44.458 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:14:44.459 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:14:44.459 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13000 2022-08-21 14:14:44.459 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:14:44.460 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:14:44.460 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:14:44.460 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:14:44.461 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:44.461 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354331 2022-08-21 14:14:44.668 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:60 2022-08-21 14:14:44.668 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:87 2022-08-21 14:14:44.719 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:14:44.720 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 238, "U2": 239, "U3": 241, "I1": 12441, "I2": 12549, "I3": 12423, "P": 8914570, "PF": 997, "E pres": 401, "E total": 22520056, "Serial": "20894742", "Sec": 354331 } ' 2022-08-21 14:14:44.722 - debug: kecontact.0 (6953) Available surplus: 9021.175 2022-08-21 14:14:44.722 - debug: kecontact.0 (6953) power: 9021.175 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = 13100 2022-08-21 14:14:44.723 - debug: kecontact.0 (6953) wallbox set to charging maximum of 13100 mA 2022-08-21 14:14:44.723 - info: kecontact.0 (6953) regulate wallbox from 13000 to 13100mA 2022-08-21 14:14:44.738 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:44.742 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:238 2022-08-21 14:14:44.742 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:14:44.743 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:241 2022-08-21 14:14:44.743 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12441 2022-08-21 14:14:44.744 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12549 2022-08-21 14:14:44.744 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12423 2022-08-21 14:14:44.744 - debug: kecontact.0 (6953) update state kecontact.0.p with value:8914570 2022-08-21 14:14:44.744 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.7 2022-08-21 14:14:44.749 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:40.1 2022-08-21 14:14:44.749 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252005.6 2022-08-21 14:14:44.750 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:44.750 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354331 2022-08-21 14:14:44.751 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:14:44.751 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:9021 2022-08-21 14:14:45.019 - debug: kecontact.0 (6953) Sent 'currtime 13100 1' to 192.168.2.186:7090 2022-08-21 14:14:45.021 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-21 14:14:45.021 - debug: kecontact.0 (6953) Received TCH-OK :done 2022-08-21 14:14:45.029 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:45.320 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:14:45.322 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 417, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354332 } ' 2022-08-21 14:14:45.323 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:14:45.323 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:14:45.324 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:14:45.324 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:14:45.324 - debug: kecontact.0 (6953) Unknown Session value received: E pres=417 2022-08-21 14:14:45.325 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:14:45.325 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:14:45.325 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:14:45.325 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:14:45.326 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:14:45.326 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:14:45.326 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:14:45.327 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:14:45.327 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:14:45.327 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354332 2022-08-21 14:14:45.342 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:46.504 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 447}' 2022-08-21 14:14:46.505 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 447}' 2022-08-21 14:14:46.512 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:46.515 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:44.7 2022-08-21 14:14:46.516 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:46.516 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:44.7 2022-08-21 14:14:46.672 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-21 14:14:46.673 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:34 2022-08-21 14:14:48.176 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Max curr": 13100}' 2022-08-21 14:14:48.178 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 13100}' 2022-08-21 14:14:48.185 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:48.189 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:14:48.190 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:48.190 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:14:48.678 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:14:48.679 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:39 2022-08-21 14:14:50.475 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 546}' 2022-08-21 14:14:50.476 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 546}' 2022-08-21 14:14:50.486 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:50.486 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:54.6 2022-08-21 14:14:50.488 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:50.490 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:54.6 2022-08-21 14:14:50.667 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:44 2022-08-21 14:14:50.668 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:52.693 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:60 2022-08-21 14:14:52.695 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:13 2022-08-21 14:14:54.445 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 646}' 2022-08-21 14:14:54.447 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 646}' 2022-08-21 14:14:54.454 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:54.455 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:64.6 2022-08-21 14:14:54.457 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:54.458 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:64.6 2022-08-21 14:14:54.776 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:30 2022-08-21 14:14:54.776 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:28 2022-08-21 14:14:56.666 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:89 2022-08-21 14:14:56.666 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:58.416 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 746}' 2022-08-21 14:14:58.417 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 746}' 2022-08-21 14:14:58.423 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:58.426 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:74.6 2022-08-21 14:14:58.426 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:58.426 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:74.6 2022-08-21 14:14:58.688 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:56 2022-08-21 14:14:58.689 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:14:59.419 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:14:59.420 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13100, "Max curr %": 218, "Curr HW": 20000, "Curr user": 13100, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13100, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354346 } ' 2022-08-21 14:14:59.436 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:59.441 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:14:59.441 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:14:59.441 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:14:59.442 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:14:59.442 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:14:59.442 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:14:59.442 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:14:59.443 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:14:59.443 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:14:59.445 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.8 2022-08-21 14:14:59.449 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:14:59.449 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13100 2022-08-21 14:14:59.450 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:14:59.450 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:14:59.450 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13100 2022-08-21 14:14:59.450 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:14:59.451 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:14:59.451 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:14:59.451 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:14:59.451 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:59.453 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354346 2022-08-21 14:14:59.720 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:14:59.722 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 239, "U2": 239, "U3": 242, "I1": 12538, "I2": 12663, "I3": 12553, "P": 9047435, "PF": 998, "E pres": 777, "E total": 22520432, "Serial": "20894742", "Sec": 354346 } ' 2022-08-21 14:14:59.725 - info: kecontact.0 (6953) vehicle (re)starts to charge 2022-08-21 14:14:59.726 - debug: kecontact.0 (6953) Available surplus: 8858.57 2022-08-21 14:14:59.745 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:14:59.749 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:239 2022-08-21 14:14:59.750 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:14:59.750 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:242 2022-08-21 14:14:59.751 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12538 2022-08-21 14:14:59.752 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12663 2022-08-21 14:14:59.753 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12553 2022-08-21 14:14:59.753 - debug: kecontact.0 (6953) update state kecontact.0.p with value:9047435 2022-08-21 14:14:59.754 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.8 2022-08-21 14:14:59.759 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:77.7 2022-08-21 14:14:59.760 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252043.2 2022-08-21 14:14:59.760 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:14:59.761 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354346 2022-08-21 14:14:59.761 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargeTimestamp with value:Sun Aug 21 2022 14:14:59 GMT+0200 (Central European Summer Time) 2022-08-21 14:14:59.762 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:14:59.762 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:8859 2022-08-21 14:15:00.019 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:15:00.021 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 783, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354346 } ' 2022-08-21 14:15:00.022 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:15:00.022 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:15:00.022 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:15:00.022 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: E pres=783 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:15:00.023 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:15:00.024 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:15:00.024 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:15:00.024 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354346 2022-08-21 14:15:00.030 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:00.717 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:50 2022-08-21 14:15:00.717 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:12 2022-08-21 14:15:02.388 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 846}' 2022-08-21 14:15:02.389 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 846}' 2022-08-21 14:15:02.394 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:02.394 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:84.6 2022-08-21 14:15:02.396 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:02.396 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:84.6 2022-08-21 14:15:02.714 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:02.714 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:3 2022-08-21 14:15:05.200 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-21 14:15:05.200 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:1 2022-08-21 14:15:06.358 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 945}' 2022-08-21 14:15:06.360 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 945}' 2022-08-21 14:15:06.369 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:06.372 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:94.5 2022-08-21 14:15:06.373 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:06.374 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:94.5 2022-08-21 14:15:06.733 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-21 14:15:06.734 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:2 2022-08-21 14:15:08.744 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:51 2022-08-21 14:15:08.746 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:10.329 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1045}' 2022-08-21 14:15:10.331 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1045}' 2022-08-21 14:15:10.340 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:10.343 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:104.5 2022-08-21 14:15:10.355 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:10.357 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:104.5 2022-08-21 14:15:10.720 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:10.722 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:9 2022-08-21 14:15:12.713 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:57 2022-08-21 14:15:12.714 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:14.300 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1145}' 2022-08-21 14:15:14.302 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1145}' 2022-08-21 14:15:14.320 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:14.320 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:114.5 2022-08-21 14:15:14.324 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:14.325 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:114.5 2022-08-21 14:15:14.420 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:15:14.422 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13100, "Max curr %": 218, "Curr HW": 20000, "Curr user": 13100, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13100, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354361 } ' 2022-08-21 14:15:14.456 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:14.465 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:15:14.466 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:15:14.467 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:15:14.467 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:15:14.468 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:15:14.468 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:15:14.468 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:15:14.469 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:15:14.469 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:15:14.473 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.8 2022-08-21 14:15:14.474 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:15:14.474 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13100 2022-08-21 14:15:14.475 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:15:14.476 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:15:14.476 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13100 2022-08-21 14:15:14.477 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:15:14.477 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:15:14.477 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:15:14.478 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:15:14.478 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:14.478 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354361 2022-08-21 14:15:14.699 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:66 2022-08-21 14:15:14.699 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:14.719 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:15:14.720 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 239, "U2": 239, "U3": 241, "I1": 12472, "I2": 12674, "I3": 12562, "P": 9033149, "PF": 997, "E pres": 1156, "E total": 22520811, "Serial": "20894742", "Sec": 354361 } ' 2022-08-21 14:15:14.721 - debug: kecontact.0 (6953) Available surplus: 8981.435 2022-08-21 14:15:14.721 - debug: kecontact.0 (6953) power: 8981.435 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = 13000 2022-08-21 14:15:14.722 - debug: kecontact.0 (6953) wallbox set to charging maximum of 13000 mA 2022-08-21 14:15:14.722 - info: kecontact.0 (6953) regulate wallbox from 13100 to 13000mA 2022-08-21 14:15:14.731 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:14.740 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:239 2022-08-21 14:15:14.740 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:15:14.743 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:241 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12472 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12674 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12562 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.p with value:9033149 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.7 2022-08-21 14:15:14.744 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:115.6 2022-08-21 14:15:14.745 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252081.1 2022-08-21 14:15:14.745 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:14.749 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354361 2022-08-21 14:15:14.749 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:15:14.750 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:8981 2022-08-21 14:15:14.752 - debug: kecontact.0 (6953) update state kecontact.0.statistics.regardTimestamp with value:null 2022-08-21 14:15:15.019 - debug: kecontact.0 (6953) Sent 'currtime 13000 1' to 192.168.2.186:7090 2022-08-21 14:15:15.020 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-21 14:15:15.020 - debug: kecontact.0 (6953) Received TCH-OK :done 2022-08-21 14:15:15.025 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:15.320 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:15:15.322 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 1166, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354362 } ' 2022-08-21 14:15:15.323 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:15:15.323 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:15:15.324 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:15:15.324 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:15:15.325 - debug: kecontact.0 (6953) Unknown Session value received: E pres=1166 2022-08-21 14:15:15.325 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:15:15.325 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:15:15.326 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:15:15.326 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:15:15.326 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:15:15.327 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:15:15.327 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:15:15.327 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:15:15.327 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:15:15.328 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354362 2022-08-21 14:15:15.344 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:16.724 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:20 2022-08-21 14:15:16.725 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:23 2022-08-21 14:15:18.271 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1245}' 2022-08-21 14:15:18.275 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1245}' 2022-08-21 14:15:18.289 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:18.289 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:124.5 2022-08-21 14:15:18.289 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:18.290 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:124.5 2022-08-21 14:15:18.740 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:40 2022-08-21 14:15:18.740 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:7 2022-08-21 14:15:20.705 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:40 2022-08-21 14:15:20.706 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:21.197 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Max curr": 13000}' 2022-08-21 14:15:21.200 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 13000}' 2022-08-21 14:15:21.210 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:21.214 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:15:21.215 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:21.215 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:15:22.242 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1345}' 2022-08-21 14:15:22.244 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1345}' 2022-08-21 14:15:22.253 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:22.256 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:134.5 2022-08-21 14:15:22.259 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:22.262 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:134.5 2022-08-21 14:15:22.711 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:30 2022-08-21 14:15:22.716 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:69 2022-08-21 14:15:24.749 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-21 14:15:24.752 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:121 2022-08-21 14:15:26.422 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1448}' 2022-08-21 14:15:26.424 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1448}' 2022-08-21 14:15:26.433 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:26.437 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:144.8 2022-08-21 14:15:26.438 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:26.440 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:144.8 2022-08-21 14:15:26.709 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:26.710 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:72 2022-08-21 14:15:28.720 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:28.720 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:94 2022-08-21 14:15:29.420 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:15:29.422 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13000, "Max curr %": 216, "Curr HW": 20000, "Curr user": 13000, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13000, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354376 } ' 2022-08-21 14:15:29.448 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:29.453 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:15:29.454 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:15:29.454 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:15:29.455 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:15:29.455 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:15:29.455 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:15:29.456 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:15:29.456 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:15:29.462 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:15:29.463 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.6 2022-08-21 14:15:29.463 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:15:29.464 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13000 2022-08-21 14:15:29.464 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:15:29.464 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:15:29.465 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13000 2022-08-21 14:15:29.465 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:15:29.465 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:15:29.466 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:15:29.466 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:15:29.466 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:29.467 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354376 2022-08-21 14:15:29.721 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:15:29.722 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 238, "U2": 239, "U3": 242, "I1": 12345, "I2": 12465, "I3": 12401, "P": 8920021, "PF": 998, "E pres": 1526, "E total": 22521181, "Serial": "20894742", "Sec": 354376 } ' 2022-08-21 14:15:29.725 - debug: kecontact.0 (6953) Available surplus: 9127.149 2022-08-21 14:15:29.743 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:29.748 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:238 2022-08-21 14:15:29.749 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:15:29.749 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:242 2022-08-21 14:15:29.749 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12345 2022-08-21 14:15:29.750 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12465 2022-08-21 14:15:29.750 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12401 2022-08-21 14:15:29.751 - debug: kecontact.0 (6953) update state kecontact.0.p with value:8920021 2022-08-21 14:15:29.751 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.8 2022-08-21 14:15:29.756 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:152.6 2022-08-21 14:15:29.757 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252118.1 2022-08-21 14:15:29.757 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:29.757 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354376 2022-08-21 14:15:29.758 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:15:29.758 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:9127 2022-08-21 14:15:30.021 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:15:30.023 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 1536, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354376 } ' 2022-08-21 14:15:30.024 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:15:30.024 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:15:30.025 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:15:30.025 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:15:30.025 - debug: kecontact.0 (6953) Unknown Session value received: E pres=1536 2022-08-21 14:15:30.025 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:15:30.026 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:15:30.026 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:15:30.026 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:15:30.026 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:15:30.027 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:15:30.027 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:15:30.027 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:15:30.027 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:15:30.028 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354376 2022-08-21 14:15:30.040 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:30.393 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1547}' 2022-08-21 14:15:30.395 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1547}' 2022-08-21 14:15:30.408 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:30.409 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:154.7 2022-08-21 14:15:30.409 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:30.415 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:154.7 2022-08-21 14:15:30.731 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:30.731 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:101 2022-08-21 14:15:32.742 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:32.743 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:118 2022-08-21 14:15:34.364 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1645}' 2022-08-21 14:15:34.365 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1645}' 2022-08-21 14:15:34.375 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:34.375 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:164.5 2022-08-21 14:15:34.375 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:34.375 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:164.5 2022-08-21 14:15:34.718 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:34.718 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:76 2022-08-21 14:15:36.706 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:36.706 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:103 2022-08-21 14:15:38.544 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1748}' 2022-08-21 14:15:38.545 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1748}' 2022-08-21 14:15:38.549 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:38.552 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:174.8 2022-08-21 14:15:38.553 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:38.554 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:174.8 2022-08-21 14:15:38.707 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:38.707 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:127 2022-08-21 14:15:40.710 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:10 2022-08-21 14:15:40.711 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:72 2022-08-21 14:15:42.515 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1846}' 2022-08-21 14:15:42.523 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1846}' 2022-08-21 14:15:42.528 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:42.530 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:184.6 2022-08-21 14:15:42.530 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:42.530 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:184.6 2022-08-21 14:15:42.697 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:42.698 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:131 2022-08-21 14:15:44.420 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:15:44.422 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13000, "Max curr %": 216, "Curr HW": 20000, "Curr user": 13000, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13000, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354391 } ' 2022-08-21 14:15:44.448 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:44.453 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:15:44.454 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:15:44.454 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:15:44.455 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:15:44.455 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:15:44.455 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:15:44.456 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:15:44.456 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:15:44.460 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13000 2022-08-21 14:15:44.461 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.6 2022-08-21 14:15:44.461 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:15:44.462 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13000 2022-08-21 14:15:44.462 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:15:44.463 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:15:44.463 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13000 2022-08-21 14:15:44.463 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:15:44.464 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:15:44.464 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:15:44.464 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:15:44.465 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:44.465 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354391 2022-08-21 14:15:44.712 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:44.714 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:100 2022-08-21 14:15:44.721 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:15:44.722 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 239, "U2": 239, "U3": 242, "I1": 12348, "I2": 12387, "I3": 12406, "P": 8913600, "PF": 998, "E pres": 1898, "E total": 22521553, "Serial": "20894742", "Sec": 354391 } ' 2022-08-21 14:15:44.723 - debug: kecontact.0 (6953) Available surplus: 9020.021 2022-08-21 14:15:44.724 - debug: kecontact.0 (6953) power: 9020.021 / voltage: 230 * 1000 / delta: 100 / phases: 3 * delta = 13100 2022-08-21 14:15:44.724 - debug: kecontact.0 (6953) wallbox set to charging maximum of 13100 mA 2022-08-21 14:15:44.725 - info: kecontact.0 (6953) regulate wallbox from 13000 to 13100mA 2022-08-21 14:15:44.739 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:44.744 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:239 2022-08-21 14:15:44.744 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:15:44.745 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:242 2022-08-21 14:15:44.745 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12348 2022-08-21 14:15:44.745 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12387 2022-08-21 14:15:44.746 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12406 2022-08-21 14:15:44.746 - debug: kecontact.0 (6953) update state kecontact.0.p with value:8913600 2022-08-21 14:15:44.746 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.8 2022-08-21 14:15:44.746 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:189.8 2022-08-21 14:15:44.755 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252155.3 2022-08-21 14:15:44.756 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:44.756 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354391 2022-08-21 14:15:44.757 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:15:44.757 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:9020 2022-08-21 14:15:44.757 - debug: kecontact.0 (6953) update state kecontact.0.statistics.regardTimestamp with value:null 2022-08-21 14:15:45.021 - debug: kecontact.0 (6953) Sent 'currtime 13100 1' to 192.168.2.186:7090 2022-08-21 14:15:45.023 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: 'TCH-OK :done ' 2022-08-21 14:15:45.023 - debug: kecontact.0 (6953) Received TCH-OK :done 2022-08-21 14:15:45.030 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:45.321 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:15:45.323 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 1914, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354392 } ' 2022-08-21 14:15:45.323 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: E pres=1914 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:15:45.324 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:15:45.325 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354392 2022-08-21 14:15:45.332 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:46.486 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 1945}' 2022-08-21 14:15:46.488 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 1945}' 2022-08-21 14:15:46.495 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:46.497 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:194.5 2022-08-21 14:15:46.500 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:46.503 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:194.5 2022-08-21 14:15:46.737 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:46.737 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:118 2022-08-21 14:15:48.715 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:48.720 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:99 2022-08-21 14:15:50.667 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 2048}' 2022-08-21 14:15:50.668 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 2048}' 2022-08-21 14:15:50.677 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:50.680 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:204.8 2022-08-21 14:15:50.680 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:50.681 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:204.8 2022-08-21 14:15:50.748 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:0 2022-08-21 14:15:50.749 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:107 2022-08-21 14:15:51.294 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"Max curr": 13100}' 2022-08-21 14:15:51.295 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"Max curr": 13100}' 2022-08-21 14:15:51.314 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:51.315 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:15:51.315 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:51.315 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:15:52.732 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:60 2022-08-21 14:15:52.734 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:7 2022-08-21 14:15:54.637 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 2147}' 2022-08-21 14:15:54.640 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 2147}' 2022-08-21 14:15:54.648 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:54.650 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:214.7 2022-08-21 14:15:54.652 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:54.654 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:214.7 2022-08-21 14:15:54.810 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:8 2022-08-21 14:15:54.810 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:56.716 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:40 2022-08-21 14:15:56.716 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:17 2022-08-21 14:15:58.609 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 2247}' 2022-08-21 14:15:58.612 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 2247}' 2022-08-21 14:15:58.620 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:58.623 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:224.7 2022-08-21 14:15:58.625 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:58.627 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:224.7 2022-08-21 14:15:58.736 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:31 2022-08-21 14:15:58.736 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:15:59.421 - debug: kecontact.0 (6953) Sent 'report 2' to 192.168.2.186:7090 2022-08-21 14:15:59.423 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "2", "State": 3, "Error1": 0, "Error2": 0, "Plug": 7, "AuthON": 0, "Authreq": 0, "Enable sys": 1, "Enable user": 1, "Max curr": 13100, "Max curr %": 218, "Curr HW": 20000, "Curr user": 13100, "Curr FS": 0, "Tmo FS": 0, "Curr timer": 13100, "Tmo CT": 0, "Setenergy": 0, "Output": 0, "Input": 0, "Serial": "20894742", "Sec": 354406 } ' 2022-08-21 14:15:59.459 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:59.460 - debug: kecontact.0 (6953) update state kecontact.0.state with value:3 2022-08-21 14:15:59.460 - debug: kecontact.0 (6953) update state kecontact.0.error1 with value:0 2022-08-21 14:15:59.460 - debug: kecontact.0 (6953) update state kecontact.0.error2 with value:0 2022-08-21 14:15:59.461 - debug: kecontact.0 (6953) update state kecontact.0.plug with value:7 2022-08-21 14:15:59.466 - debug: kecontact.0 (6953) update state kecontact.0.authON with value:false 2022-08-21 14:15:59.467 - debug: kecontact.0 (6953) update state kecontact.0.authreq with value:false 2022-08-21 14:15:59.467 - debug: kecontact.0 (6953) update state kecontact.0.enableSys with value:true 2022-08-21 14:15:59.468 - debug: kecontact.0 (6953) update state kecontact.0.enableUser with value:true 2022-08-21 14:15:59.468 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrent with value:13100 2022-08-21 14:15:59.469 - debug: kecontact.0 (6953) update state kecontact.0.maxCurrentPercent with value:21.8 2022-08-21 14:15:59.469 - debug: kecontact.0 (6953) update state kecontact.0.currentHardware with value:20000 2022-08-21 14:15:59.470 - debug: kecontact.0 (6953) update state kecontact.0.currentUser with value:13100 2022-08-21 14:15:59.470 - debug: kecontact.0 (6953) update state kecontact.0.currentFailsafe with value:0 2022-08-21 14:15:59.470 - debug: kecontact.0 (6953) update state kecontact.0.timeoutFailsafe with value:0 2022-08-21 14:15:59.471 - debug: kecontact.0 (6953) update state kecontact.0.currentTimer with value:13100 2022-08-21 14:15:59.471 - debug: kecontact.0 (6953) update state kecontact.0.timeoutCurrentTimer with value:0 2022-08-21 14:15:59.471 - debug: kecontact.0 (6953) update state kecontact.0.setenergy with value:0 2022-08-21 14:15:59.476 - debug: kecontact.0 (6953) update state kecontact.0.output with value:false 2022-08-21 14:15:59.477 - debug: kecontact.0 (6953) update state kecontact.0.input with value:false 2022-08-21 14:15:59.478 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:59.478 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354406 2022-08-21 14:15:59.722 - debug: kecontact.0 (6953) Sent 'report 3' to 192.168.2.186:7090 2022-08-21 14:15:59.723 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "3", "U1": 238, "U2": 239, "U3": 241, "I1": 12528, "I2": 12596, "I3": 12580, "P": 9033752, "PF": 998, "E pres": 2273, "E total": 22521928, "Serial": "20894742", "Sec": 354406 } ' 2022-08-21 14:15:59.724 - debug: kecontact.0 (6953) Available surplus: 8882.6 2022-08-21 14:15:59.732 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:15:59.735 - debug: kecontact.0 (6953) update state kecontact.0.u1 with value:238 2022-08-21 14:15:59.735 - debug: kecontact.0 (6953) update state kecontact.0.u2 with value:239 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.u3 with value:241 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.i1 with value:12528 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.i2 with value:12596 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.i3 with value:12580 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.p with value:9033752 2022-08-21 14:15:59.736 - debug: kecontact.0 (6953) update state kecontact.0.pf with value:99.8 2022-08-21 14:15:59.737 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:227.3 2022-08-21 14:15:59.737 - debug: kecontact.0 (6953) update state kecontact.0.eTotal with value:2252192.8 2022-08-21 14:15:59.737 - debug: kecontact.0 (6953) update state kecontact.0.serial with value:20894742 2022-08-21 14:15:59.740 - debug: kecontact.0 (6953) update state kecontact.0.uptime with value:354406 2022-08-21 14:15:59.740 - debug: kecontact.0 (6953) update state kecontact.0.statistics.chargingPhases with value:3 2022-08-21 14:15:59.740 - debug: kecontact.0 (6953) update state kecontact.0.statistics.surplus with value:8883 2022-08-21 14:16:00.022 - debug: kecontact.0 (6953) Sent 'report 100' to 192.168.2.186:7090 2022-08-21 14:16:00.024 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{ "ID": "100", "Session ID": 165, "Curr HW": 20000, "E start": 22519655, "E pres": 2278, "started[s]": 1661084061, "ended[s]": 0, "started": "2022-08-21 12:14:21.000", "ended": "0", "reason": 5, "timeQ": 0, "RFID tag": "0000000000000000", "RFID class": "00000000000000000000", "Serial": "20894742", "Sec": 354406 } ' 2022-08-21 14:16:00.024 - debug: kecontact.0 (6953) History ID received: 00 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: Session ID=165 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: Curr HW=20000 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: E start=22519655 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: E pres=2278 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: started[s]=1661084061 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: ended[s]=0 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: started=2022-08-21 12:14:21.000 2022-08-21 14:16:00.025 - debug: kecontact.0 (6953) Unknown Session value received: ended=0 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: reason=5 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: timeQ=0 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: RFID tag=0000000000000000 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: RFID class=00000000000000000000 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: Serial=20894742 2022-08-21 14:16:00.026 - debug: kecontact.0 (6953) Unknown Session value received: Sec=354406 2022-08-21 14:16:00.033 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:16:00.761 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:41 2022-08-21 14:16:00.762 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:16:02.579 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 2347}' 2022-08-21 14:16:02.581 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 2347}' 2022-08-21 14:16:02.608 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:16:02.609 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:234.7 2022-08-21 14:16:02.609 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:16:02.611 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:234.7 2022-08-21 14:16:02.746 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:64 2022-08-21 14:16:02.746 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:0 2022-08-21 14:16:05.219 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:50 2022-08-21 14:16:05.220 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:15 2022-08-21 14:16:06.551 - debug: kecontact.0 (6953) UDP datagram from 192.168.2.186:7090: '{"E pres": 2447}' 2022-08-21 14:16:06.553 - debug: kecontact.0 (6953) UDP broadcast datagram from 192.168.2.186:7090: '{"E pres": 2447}' 2022-08-21 14:16:06.560 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:16:06.563 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:244.7 2022-08-21 14:16:06.565 - debug: kecontact.0 (6953) update state kecontact.0.info.connection with value:true 2022-08-21 14:16:06.567 - debug: kecontact.0 (6953) update state kecontact.0.ePres with value:244.7 2022-08-21 14:16:06.716 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.regard with value:50 2022-08-21 14:16:06.716 - debug: kecontact.0 (6953) update state kecontact.0.javascript.0.powerwall.surplus with value:1 ```

Sneak-L8 commented 2 years ago

Thanks for that second log. I also recognized the missing "vegicle (re)starts to charge" message. I see, that your ID.3 leads to "normal" values of amperage on alle phases as well as the power (12A, about 9kW). Your Tesla just consumes 21 W at the beginning with corresponding amperage of 0,2A. Charging phases are recognized by current of 0,25A and above. Charging of vehicle is recognized by power consuption of 100W and above. So charging phases are detected correctly, but charging did not take place.

It seems that your Telsa takes quite a long time to start charging. Then, your surplus is updated (you do it every secord by a script, don't you?) and power consumption raises to normal charging values. But power information of Wallbox is not yet updated. I see that data from wallbox is received and should be updated, but calculation takes place before data is written into states. It looks like updating data in states is slower than execution of calculation after all data of datagram from wallbox is processed. But why this problem only occurs with Tesla? Perhaps because this vehicle is too slow with starting charging process... I did a fast fix. So please update adapter with newest version on git. I hope it works by that. Please let me know.

BTW: does your Tesla start with current of 5A? Otherwise you should set minimum amperage to default value of 6000 (= 6A) since values below 6000 are raised to the default value.

gogfried commented 2 years ago

Hey @Sneak-L8, sorry for my delayed response but the sun didn't come out long enough for me to properly test. Today, I could test and observe the change for a bit longer. The good news: This has definitely helped a lot. The wallbox starts charing relatively reliable. Still it sometimes happens that it starts and stops more or less immediately but this happens at lot less.

On your question on the 5A: The Tesla can charge at 5A but if I understand you correctly the Wallbox is not able to do that?

Sneak-L8 commented 2 years ago

Thanks for that positive feedback. Yes, Keba can only start charging at 6A. As of UDP guide valid values (besides 0) start at 6000. If I should check the remaining issues please send a new log of such a charging session. Otherwise i would close this issue.

Sneak-L8 commented 2 years ago

I updated your posts according to that post, so collapse the logs.

Sneak-L8 commented 2 years ago

I again updated git version for better calculation (also amperage values have to be updated immediately to get charging phases). But for your randomly abort of charging session it will probably not help. So just send me a log if I should check.

gogfried commented 2 years ago

Thanks a lot @Sneak-L8! I tried to create a log with the sudden stops but couldn’t reproduce the issue anymore. It seems this was a temporary problem.

So seems for now the issue is solved. Thanks for your help on this. I‘m very glad to be able to reliably charge my car again.

Sneak-L8 commented 2 years ago

You're welcome :-) Just to be sure: you tested again with the latest version on git (from 6 days ago)?

gogfried commented 1 year ago

Yes exactly

Sneak-L8 commented 1 year ago

@gogfried I tested some different handling of updating power and amperage from Keba. Could you test if with this version in github, your Tesla again has problems with charging of if it still works fine? You now should also be possible to set minimum amperage to 5A start start charging. It's not documented by Keba but it seems that it works fine with 5A.