Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Improve NRG BLE logging #164

Merged
merged 7 commits into from May 19, 2020
Merged

Improve NRG BLE logging #164

merged 7 commits into from May 19, 2020

Conversation

andig
Copy link
Member

@andig andig commented May 16, 2020

No description provided.

@andig andig added enhancement New feature or request internals and removed enhancement New feature or request labels May 16, 2020
@premultiply
Copy link
Member

Problem: Ist der NRGkick nicht da blockiert EVCC komplett.

@premultiply
Copy link
Member

premultiply commented May 18, 2020 •
edited

Testweise war der NRGkick zu beginn nicht angeschlossen. EVCC komplett blockiert.
Wurde dann angesteckt. Fahrzeug fing an mit 6A zu laden.

Hier ein Logauszug:

pi@evcc:~/evcc $ git checkout fix/nrg-log
Branch 'fix/nrg-log' folgt nun Remote-Branch 'fix/nrg-log' von 'origin'.
Zu neuem Branch 'fix/nrg-log' gewechselt
pi@evcc:~/evcc $ sudo ~/go/bin/go run main.go
[main] INFO 2020/05/18 11:21:17 evcc dev (HEAD)
[main] INFO 2020/05/18 11:21:17 using config file /home/pi/evcc/evcc.yaml
[main] INFO 2020/05/18 11:21:17 listening at 0.0.0.0:7070
[nrgbt] WARN 2020/05/18 11:21:17 -- experimental --
[load] INFO 2020/05/18 11:21:17 main loadpoint config: vehicle — grid ✓ pv — battery — charge —
[load] INFO 2020/05/18 11:21:17 main charger config: power ✓ energy — timer —
[load] INFO 2020/05/18 11:21:17 main charge mode: pv
[nrgbt] TRACE 2020/05/18 11:24:24 read 8f75bba0-c903-11e4-9fe8-0002a5d6b15d
[nrgbt] TRACE 2020/05/18 11:24:24 EOF
[load] ERROR 2020/05/18 11:24:24 main charger error: EOF
[load] DEBUG 2020/05/18 11:24:24 main set charge current: 6A
[nrgbt] TRACE 2020/05/18 11:24:26 read 8f75bba0-c903-11e4-9fe8-0002a5d6b15d 14001d1000005f0000200400bd
[nrgbt] TRACE 2020/05/18 11:24:26 write settings: {PIN:xxxx Current:6 ChargingEnergyLimit:19997 KWhPer100:29 AmountPerKWh:16 Pad:0 Efficiency:95 PauseCharging:false BLETransmissionPower:4 PadTail:0}
[nrgbt] TRACE 2020/05/18 11:24:26 write 14b3afc0-ad1b-11e4-baab-0002a5d6b15d 028b064e1d001d1000005f00040000000000
[load] DEBUG 2020/05/18 11:24:28 main grid power: -4005.2W
[nrgbt] TRACE 2020/05/18 11:24:30 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013890017000000000368
[nrgbt] TRACE 2020/05/18 11:24:30 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:30 main charge power: 0.0W
[nrgbt] TRACE 2020/05/18 11:24:33 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013890017000000000367
[nrgbt] TRACE 2020/05/18 11:24:33 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:33 main charger status: B
[load] DEBUG 2020/05/18 11:24:33 main target power: 4005W = 0W charge - -4005W grid - 0W residual
[load] DEBUG 2020/05/18 11:24:33 main site power -4005 < enable threshold 0
[load] DEBUG 2020/05/18 11:24:33 main start enable timer
[load] DEBUG 2020/05/18 11:24:33 main target charge current: 0A
[load] DEBUG 2020/05/18 11:24:38 main grid power: -3980.7W
[nrgbt] TRACE 2020/05/18 11:24:38 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013890017000000000359
[nrgbt] TRACE 2020/05/18 11:24:38 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:38 main charge power: 0.0W
[nrgbt] TRACE 2020/05/18 11:24:40 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013890017000000000359
[nrgbt] TRACE 2020/05/18 11:24:40 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:40 main charger status: B
[load] DEBUG 2020/05/18 11:24:40 main target power: 3981W = 0W charge - -3981W grid - 0W residual
[load] DEBUG 2020/05/18 11:24:40 main site power -3981 < enable threshold 0
[load] DEBUG 2020/05/18 11:24:40 main target charge current: 0A
[load] DEBUG 2020/05/18 11:24:43 main grid power: -3962.5W
[nrgbt] TRACE 2020/05/18 11:24:43 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013890017000000000359
[nrgbt] TRACE 2020/05/18 11:24:43 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:43 main charge power: 0.0W
[nrgbt] TRACE 2020/05/18 11:24:45 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013880017000000000362
[nrgbt] TRACE 2020/05/18 11:24:45 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5000 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:3}
[load] DEBUG 2020/05/18 11:24:45 main charger status: B
[load] DEBUG 2020/05/18 11:24:45 main target power: 3962W = 0W charge - -3962W grid - 0W residual
[load] DEBUG 2020/05/18 11:24:45 main site power -3962 < enable threshold 0
[load] DEBUG 2020/05/18 11:24:45 main target charge current: 0A
[load] DEBUG 2020/05/18 11:24:48 main grid power: -3967.7W
[nrgbt] TRACE 2020/05/18 11:24:48 read fd005380-b065-11e4-9ce2-0002a5d6b15d 000000000000000000001388001700000000023d
[nrgbt] TRACE 2020/05/18 11:24:48 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5000 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:24:48 main charge power: 0.0W
[nrgbt] TRACE 2020/05/18 11:24:50 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0000000000000000000013880017000000000241
[nrgbt] TRACE 2020/05/18 11:24:50 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:0 Frequency:5000 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:24:50 main charger status: C
[load] INFO 2020/05/18 11:24:50 main start charging ->
[nrgbt] TRACE 2020/05/18 11:24:52 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2980000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:24:52 read energy: {TotalEnergy:1634968 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[wrap] DEBUG 2020/05/18 11:24:52 main charge start energy: 1635kWh
[load] DEBUG 2020/05/18 11:24:52 main target power: 3968W = 0W charge - -3968W grid - 0W residual
[load] DEBUG 2020/05/18 11:24:52 main site power -3968 < enable threshold 0
[load] DEBUG 2020/05/18 11:24:52 main target charge current: 0A
[push] TRACE 2020/05/18 11:24:52 telegram: sending to 266162151
[nrgbt] TRACE 2020/05/18 11:24:54 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2980000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:24:54 read energy: {TotalEnergy:1634968 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:24:54 main grid power: -2897.3W
[nrgbt] TRACE 2020/05/18 11:24:56 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113890017000000000240
[nrgbt] TRACE 2020/05/18 11:24:56 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5001 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:24:56 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:24:58 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880017000000000241
[nrgbt] TRACE 2020/05/18 11:24:58 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:24:58 main charger status: C
[load] DEBUG 2020/05/18 11:24:58 main target power: 4007W = 1110W charge - -2897W grid - 0W residual
[load] DEBUG 2020/05/18 11:24:58 main site power -2897 < enable threshold 0
[load] DEBUG 2020/05/18 11:24:58 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:01 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f29a0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:01 read energy: {TotalEnergy:1634970 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:01 main grid power: -2905.5W
[nrgbt] TRACE 2020/05/18 11:25:03 read fd005380-b065-11e4-9ce2-0002a5d6b15d 007100710000000000711388001700000000023c
[nrgbt] TRACE 2020/05/18 11:25:03 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:23 RemainingDistance:0 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:03 main charge power: 1130.0W
[nrgbt] TRACE 2020/05/18 11:25:05 read fd005380-b065-11e4-9ce2-0002a5d6b15d 007100710000000000711388001700010000023c
[nrgbt] TRACE 2020/05/18 11:25:05 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:23 RemainingDistance:1 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:05 main charger status: C
[load] DEBUG 2020/05/18 11:25:05 main target power: 4036W = 1130W charge - -2906W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:05 main site power -2906 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:05 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:07 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f29d0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:07 read energy: {TotalEnergy:1634973 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:07 main grid power: -2887.0W
[nrgbt] TRACE 2020/05/18 11:25:09 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000100000242
[nrgbt] TRACE 2020/05/18 11:25:09 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:1 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:09 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:11 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f0000000000711388001800010000023c
[nrgbt] TRACE 2020/05/18 11:25:11 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:1 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:11 main charger status: C
[load] DEBUG 2020/05/18 11:25:11 main target power: 3997W = 1110W charge - -2887W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:11 main site power -2887 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:11 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:13 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f29e0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:13 read energy: {TotalEnergy:1634974 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:13 main grid power: -3049.9W
[nrgbt] TRACE 2020/05/18 11:25:16 read fd005380-b065-11e4-9ce2-0002a5d6b15d 007100710000000000711388001800020000023b
[nrgbt] TRACE 2020/05/18 11:25:16 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:2 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:16 main charge power: 1130.0W
[nrgbt] TRACE 2020/05/18 11:25:18 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113880018000200000241
[nrgbt] TRACE 2020/05/18 11:25:18 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:2 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:18 main charger status: C
[load] DEBUG 2020/05/18 11:25:18 main target power: 4180W = 1130W charge - -3050W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:18 main site power -3050 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:18 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:20 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2a00000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:20 read energy: {TotalEnergy:1634976 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:20 main grid power: -3171.8W
[nrgbt] TRACE 2020/05/18 11:25:22 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000200000242
[nrgbt] TRACE 2020/05/18 11:25:22 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:2 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:22 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:24 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000300000241
[nrgbt] TRACE 2020/05/18 11:25:24 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:3 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:24 main charger status: C
[load] DEBUG 2020/05/18 11:25:24 main target power: 4282W = 1110W charge - -3172W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:24 main site power -3172 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:24 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:26 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2a20000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:26 read energy: {TotalEnergy:1634978 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:26 main grid power: -2822.9W
[nrgbt] TRACE 2020/05/18 11:25:28 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113880018000300000240
[nrgbt] TRACE 2020/05/18 11:25:28 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:3 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:28 main charge power: 1130.0W
[nrgbt] TRACE 2020/05/18 11:25:31 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113880018000300000242
[nrgbt] TRACE 2020/05/18 11:25:31 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:3 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:31 main charger status: C
[load] DEBUG 2020/05/18 11:25:31 main target power: 3953W = 1130W charge - -2823W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:31 main site power -2823 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:31 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:33 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2a40000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:33 read energy: {TotalEnergy:1634980 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:33 main grid power: -2910.9W
[nrgbt] TRACE 2020/05/18 11:25:35 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000400000241
[nrgbt] TRACE 2020/05/18 11:25:35 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:4 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:35 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:37 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113880018000400000243
[nrgbt] TRACE 2020/05/18 11:25:37 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:4 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:37 main charger status: C
[load] DEBUG 2020/05/18 11:25:37 main target power: 4021W = 1110W charge - -2911W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:37 main site power -2911 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:37 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:39 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2a60000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:39 read energy: {TotalEnergy:1634982 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:39 main grid power: -2909.6W
[nrgbt] TRACE 2020/05/18 11:25:41 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f007100000000007113880018000400000242
[nrgbt] TRACE 2020/05/18 11:25:41 read power: {TotalPower:111 L1:113 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:4 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:41 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:43 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113870018000500000241
[nrgbt] TRACE 2020/05/18 11:25:43 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:5 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:43 main charger status: C
[load] DEBUG 2020/05/18 11:25:43 main target power: 4020W = 1110W charge - -2910W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:43 main site power -2910 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:43 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:46 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2a80000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:46 read energy: {TotalEnergy:1634984 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:46 main grid power: -2894.7W
[nrgbt] TRACE 2020/05/18 11:25:48 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113870018000500000241
[nrgbt] TRACE 2020/05/18 11:25:48 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:5 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:48 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:50 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f007100000000007113870018000500000242
[nrgbt] TRACE 2020/05/18 11:25:50 read power: {TotalPower:111 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:5 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:50 main charger status: C
[load] DEBUG 2020/05/18 11:25:50 main target power: 4005W = 1110W charge - -2895W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:50 main site power -2895 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:50 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:52 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2ab0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:52 read energy: {TotalEnergy:1634987 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:52 main grid power: -2985.0W
[nrgbt] TRACE 2020/05/18 11:25:54 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113870018000600000241
[nrgbt] TRACE 2020/05/18 11:25:54 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:6 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:54 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:25:56 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000600000241
[nrgbt] TRACE 2020/05/18 11:25:56 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:6 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:25:56 main charger status: C
[load] DEBUG 2020/05/18 11:25:56 main target power: 4095W = 1110W charge - -2985W grid - 0W residual
[load] DEBUG 2020/05/18 11:25:56 main site power -2985 < enable threshold 0
[load] DEBUG 2020/05/18 11:25:56 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:25:58 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2ad0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:25:58 read energy: {TotalEnergy:1634989 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:25:58 main grid power: -3007.8W
[nrgbt] TRACE 2020/05/18 11:26:01 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113870018000600000242
[nrgbt] TRACE 2020/05/18 11:26:01 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:6 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:01 main charge power: 1130.0W
[nrgbt] TRACE 2020/05/18 11:26:03 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113870018000700000240
[nrgbt] TRACE 2020/05/18 11:26:03 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:7 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:03 main charger status: C
[load] DEBUG 2020/05/18 11:26:03 main target power: 4138W = 1130W charge - -3008W grid - 0W residual
[load] DEBUG 2020/05/18 11:26:03 main site power -3008 < enable threshold 0
[load] DEBUG 2020/05/18 11:26:03 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:26:05 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2af0000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:26:05 read energy: {TotalEnergy:1634991 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:26:05 main grid power: -2987.5W
[nrgbt] TRACE 2020/05/18 11:26:07 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113870018000700000243
[nrgbt] TRACE 2020/05/18 11:26:07 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:24 RemainingDistance:7 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:07 main charge power: 1130.0W
[nrgbt] TRACE 2020/05/18 11:26:09 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880018000700000241
[nrgbt] TRACE 2020/05/18 11:26:09 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:24 RemainingDistance:7 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:09 main charger status: C
[load] DEBUG 2020/05/18 11:26:09 main target power: 4118W = 1130W charge - -2988W grid - 0W residual
[load] DEBUG 2020/05/18 11:26:09 main site power -2988 < enable threshold 0
[load] DEBUG 2020/05/18 11:26:09 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:26:11 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2b10000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:26:11 read energy: {TotalEnergy:1634993 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:26:11 main grid power: -3051.2W
[nrgbt] TRACE 2020/05/18 11:26:13 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113870019000800000241
[nrgbt] TRACE 2020/05/18 11:26:13 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:25 RemainingDistance:8 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:13 main charge power: 1110.0W
[nrgbt] TRACE 2020/05/18 11:26:16 read fd005380-b065-11e4-9ce2-0002a5d6b15d 0071007100000000007113870019000800000241
[nrgbt] TRACE 2020/05/18 11:26:16 read power: {TotalPower:113 L1:113 L2:0 L3:0 PeakPower:113 Frequency:4999 Temperature:25 RemainingDistance:8 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:16 main charger status: C
[load] DEBUG 2020/05/18 11:26:16 main target power: 4161W = 1110W charge - -3051W grid - 0W residual
[load] DEBUG 2020/05/18 11:26:16 main site power -3051 < enable threshold 0
[load] DEBUG 2020/05/18 11:26:16 main target charge current: 0A
[nrgbt] TRACE 2020/05/18 11:26:18 read 0379e580-ad1b-11e4-8bdd-0002a5d6b15d 0018f2b30000329a000000b6000025324e1d00
[nrgbt] TRACE 2020/05/18 11:26:18 read energy: {TotalEnergy:1634995 EnergyLastCharge:12954 Energy2ndLastCharge:182 Energy3rdLastCharge:9522 ChargingEnergyLimit:19997 Pad:0}
[load] DEBUG 2020/05/18 11:26:18 main grid power: -3143.4W
[nrgbt] TRACE 2020/05/18 11:26:20 read fd005380-b065-11e4-9ce2-0002a5d6b15d 006f006f00000000007113880019000800000240
[nrgbt] TRACE 2020/05/18 11:26:20 read power: {TotalPower:111 L1:111 L2:0 L3:0 PeakPower:113 Frequency:5000 Temperature:25 RemainingDistance:8 Costs:0 CPSignal:2}
[load] DEBUG 2020/05/18 11:26:20 main charge power: 1110.0W
^Csignal: interrupt
pi@evcc:~/evcc $

@andig andig mentioned this pull request May 18, 2020
Closed
@andig
Copy link
Member Author

andig commented May 18, 2020

@premultiply please retest. I've added a discovery timeout of 30s that should hopefully work as a non-configurable default value?

@andig
Copy link
Member Author

andig commented May 18, 2020

Refs muka/go-bluetooth#104

@premultiply
Copy link
Member

Jetzt hatte ich mal plötzliche diese Logausgaben - was immer mir das auch sagen will:

.
.
.
[nrgbt] TRACE 2020/05/19 00:25:34 read fd005380-b065-11e4-9ce2-0002a5d6b15d 000000000000000001d7138a0022008900060364
[nrgbt] TRACE 2020/05/19 00:25:34 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:471 Frequency:5002 Temperature:34 RemainingDistance:137 Costs:6 CPSignal:3}
[load] DEBUG 2020/05/19 00:25:34 main charger status: B
[load] DEBUG 2020/05/19 00:25:37 main grid power: 350.1W
[nrgbt] TRACE 2020/05/19 00:25:37 read fd005380-b065-11e4-9ce2-0002a5d6b15d 000000000000000001d7138a0022008900060364
[nrgbt] TRACE 2020/05/19 00:25:37 read power: {TotalPower:0 L1:0 L2:0 L3:0 PeakPower:471 Frequency:5002 Temperature:34 RemainingDistance:137 Costs:6 CPSignal:3}
[load] DEBUG 2020/05/19 00:25:37 main charge power: 0.0W
[nrgbt] TRACE 2020/05/19 00:25:54 read fd005380-b065-11e4-9ce2-0002a5d6b15d
[load] ERROR 2020/05/19 00:25:58 main charge controller error: All attempts fail:
#1: EOF
#2: Not connected
#3: find device: Operation already in progress
[load] DEBUG 2020/05/19 00:25:58 main grid power: 339.1W
[load] ERROR 2020/05/19 00:26:04 main updating charge meter: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] ERROR 2020/05/19 00:26:10 main charge controller error: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] DEBUG 2020/05/19 00:26:10 main grid power: 336.6W
[load] ERROR 2020/05/19 00:26:16 main updating charge meter: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] ERROR 2020/05/19 00:26:22 main charge controller error: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] DEBUG 2020/05/19 00:26:22 main grid power: 336.0W
[load] ERROR 2020/05/19 00:26:28 main updating charge meter: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] ERROR 2020/05/19 00:26:35 main charge controller error: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
[load] DEBUG 2020/05/19 00:26:35 main grid power: 334.4W
[load] ERROR 2020/05/19 00:26:41 main updating charge meter: All attempts fail:
#1: find device: Operation already in progress
#2: find device: Operation already in progress
#3: find device: Operation already in progress
.
.
.

@andig
Copy link
Member Author

andig commented May 19, 2020

Jetzt hatte ich mal plötzliche diese Logausgaben - was immer mir das auch sagen will:

Bleibt das so oder kriegt sichs irgendwann wieder ein? Sieht aus als würde Bluetooth Discovery laufen und nicht wieder beendet werden. Beim nächsten Versuch läufts dann doppelt was offensichtlich keine gute Idee ist. Ich schaue ob man das nachdrücklich beenden kann.

@andig
Copy link
Member Author

andig commented May 19, 2020

Ich habe noch eine Änderung angehängt und merge jetzt mal as-is da schonmal deutlich besser als zu Beginn. Falls der Fehler (oder weitere) wieder auftaucht bitte neues Ticket.

@andig andig merged commit 044eb87 into master May 19, 2020
@delete-merged-branch delete-merged-branch bot deleted the fix/nrg-log branch May 19, 2020 05:28
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Reviewers
No reviews
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

Successfully merging this pull request may close these issues.

None yet
2 participants
@andig @premultiply