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.

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

deadlock in loadpoint api #18603

Open
2 tasks done
jeffborg opened this issue Feb 5, 2025 · 17 comments
Open
2 tasks done

deadlock in loadpoint api #18603

jeffborg opened this issue Feb 5, 2025 · 17 comments
Labels
question Rather clarification than issue

Comments

@jeffborg
Copy link
Contributor

jeffborg commented Feb 5, 2025

Describe the bug

evcc stops updating meters after some time, no mqtt activity from meters solar etc..
Also disconnects from mqtt broker as indicated by the broker updating the evcc/status topic to offline

Steps to reproduce

  1. run evcc 0.133.0 or nightly 0.133.0 (6fdda39)
  2. wait some time
  3. evcc will stop updating

Configuration details

interval: 30s

site:
  - title: Home
    circuit: main
    meters:
      grid: grid
      pv:
        - pv

circuits:
- name: main
  meter: grid
  maxpower: 2000
  GetMaxPower:
    source: mqtt
    topic: amber/max_demand
log: trace
levels:
  ocpp: trace

meters:
  - name: grid
    type: custom
    power:
      source: mqtt
      topic: shellies/house-energy-monitor/emeter/0/power
      timeout: 30s
      scale: 1
    energy:
      source: mqtt
      topic: shellies/house-energy-monitor/emeter/0/total
      timeout: 30s
      scale: 0.001
  - name: pv
    type: custom
    power:
      source: mqtt
      topic: energy/solar
      timeout: 30s
      scale: 1
      jq: .OutputPower
    energy:
      source: mqtt
      topic: energy/solar
      timeout: 30s
      jq: .TotalGenerateEnergy
chargers:
  - name: solax
    type: template
    template: ocpp
    remotestart: true
    metervalues: -Current.Offered
loadpoints:
  - title: Garage
    charger: solax
    vehicle: zapdos
    circuit: main
    mode: pv
    enable: # pv mode enable behavior
      delay: 3m # threshold must be exceeded for this long
      threshold: -700 # grid power threshold (in Watts, negative=export). If zero, export must exceed minimum charge power to enable
    disable: # pv mode disable behavior
      delay: 8m # threshold must be exceeded for this long
      threshold: 1000 # maximum import power (W)
mqtt:
  broker: mqtt-mosquitto
vehicles:
  - name: zapdos
    type: template
    template: teslamate
    id: 1
    capacity: 58
tariffs:
  currency: AUD
  grid:
    type: fixed
    price: 0.175
  feedin:
    type: fixed
    price: 0.01
  co2:
    type: custom
    price:
      source: mqtt
      topic: amber/co2
      timeout: 2m

Log details

(removed the ocpp lines and only the end of the log where mqtt stops updating)
[mqtt  ] TRACE 2025/02/05 10:58:52 recv energy/solar: '{"InverterStatus":1,"InputPower":3627.2,"PV1Voltage":218.6,"PV1InputCurrent":9,"PV1InputPower":1984.5,"PV2Voltage":234,"PV2InputCurrent":7.1,"PV2InputPower":1642.7,"OutputPower":3555.2,"GridFrequency":49.98,"L1ThreePhaseGridVoltage":248.3,"L1ThreePhaseGridOutputCurrent":15.7,"L1ThreePhaseGridOutputPower":3885.6,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":51.9,"TemperatureInsideIPM":51.9,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3966}'
[mqtt  ] TRACE 2025/02/05 10:58:52 recv shellies/house-energy-monitor/emeter/0/power: '107.09'
[mqtt  ] TRACE 2025/02/05 10:58:52 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[db    ] TRACE 2025/02/05 10:58:52 INSERT INTO `settings` (`key`,`value`) VALUES ("adminPassword","XXXXXXX"),("jwtSecretKey","XXXXXXXX"),("lp1.minCurrent","6"),("lp1.maxCurrent","32"),("lp1.phasesConfigured","1"),("lp1.mode","pv"),("lp1.smartCostLimit",""),("lp1.limitSoc","100"),("vehicle.zapdos.planTime","0001-01-01T00:00:00Z"),("vehicle.zapdos.planSoc","0"),("lp1.planEnergy","0"),("lp1.planTime","0001-01-01T00:00:00Z"),("residualPower","-125"),("telemetry","true"),("vehicle.zapdos.limitSoc","100"),("vehicle.zapdos.minSoc","10"),("vehicle.zapdos.repeatingPlans","[{""weekdays"":[1,2,3,4,5,6,0],""time"":""15:00"",""tz"":""Australia/Sydney"",""soc"":50,""active"":true},{""weekdays"":[6],""time"":""09:00"",""tz"":""Australia/Sydney"",""soc"":70,""active"":false}]"),("lp1.title","Garage"),("lp1.soc","{""poll"":{""mode"":""charging"",""interval"":3600000000000},""estimate"":null}"),("lp1.thresholds","{""enable"":{""delay"":180000000000,""threshold"":-384},""disable"":{""delay"":480000000000,""threshold"":1315}}") ON CONFLICT (`key`) DO UPDATE SET `value`=`excluded`.`value` 20 <nil>
[mqtt  ] TRACE 2025/02/05 10:58:52 recv shellies/house-energy-monitor/emeter/0/power: '107.09'
[mqtt  ] TRACE 2025/02/05 10:58:53 recv shellies/house-energy-monitor/emeter/0/power: '75.39'
[mqtt  ] TRACE 2025/02/05 10:58:53 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[mqtt  ] TRACE 2025/02/05 10:58:54 recv shellies/house-energy-monitor/emeter/0/power: '75.39'
[mqtt  ] TRACE 2025/02/05 10:58:54 recv energy/solar: '{"InverterStatus":1,"InputPower":3622.5,"PV1Voltage":215.6,"PV1InputCurrent":9.1,"PV1InputPower":1980.7,"PV2Voltage":232.4,"PV2InputCurrent":7.2,"PV2InputPower":1641.8,"OutputPower":3549.4,"GridFrequency":49.97,"L1ThreePhaseGridVoltage":248.2,"L1ThreePhaseGridOutputCurrent":15.7,"L1ThreePhaseGridOutputPower":3862.2,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":51.9,"TemperatureInsideIPM":51.9,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3967}'
[mqtt  ] TRACE 2025/02/05 10:58:55 recv shellies/house-energy-monitor/emeter/0/power: '175.85'
[mqtt  ] TRACE 2025/02/05 10:58:55 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[mqtt  ] TRACE 2025/02/05 10:58:56 recv shellies/house-energy-monitor/emeter/0/power: '175.85'
[mqtt  ] TRACE 2025/02/05 10:58:56 recv shellies/house-energy-monitor/emeter/0/power: '98.64'
[mqtt  ] TRACE 2025/02/05 10:58:56 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[mqtt  ] TRACE 2025/02/05 10:58:56 recv shellies/house-energy-monitor/emeter/0/power: '98.64'
[site  ] DEBUG 2025/02/05 10:58:57 ----
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge power: 1525W
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge currents: [6.2 0 0]A
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargePower: 1525
[circuit-main] DEBUG 2025/02/05 10:58:57 power: 98.64W
[circuit-main] DEBUG 2025/02/05 10:58:57 current: 0A
[site  ] DEBUG 2025/02/05 10:58:57 grid power: 99W
[cache ] TRACE 2025/02/05 10:58:57 circuits: map[main:{98.64 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:58:57 batteryGridChargeActive: false
[site  ] DEBUG 2025/02/05 10:58:57 pv 1 power: 3549W
[site  ] DEBUG 2025/02/05 10:58:57 site power: -26W
[cache ] TRACE 2025/02/05 10:58:57 grid: {98.64 12822.3157 [] [] 0 <nil> <nil> <nil>}
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:58:57 pvPower: 3549.4
[cache ] TRACE 2025/02/05 10:58:57 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:58:57 pv: [{3549.4 20384.2 [] [] 0 <nil> <nil> <nil>}]
[cache ] TRACE 2025/02/05 10:58:57 homePower: 2123.04
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge voltages: [246 0 0]V
[cache ] TRACE 2025/02/05 10:58:57 lp-1/smartCostActive: false
[lp-1  ] DEBUG 2025/02/05 10:58:57 detected connected phases: 1p
[cache ] TRACE 2025/02/05 10:58:57 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge total import: 324.409kWh
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeVoltages: [245.7 0 0]
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeTotalImport: 324.409
[lp-1  ] DEBUG 2025/02/05 10:58:57 charger status: B
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanId: 2
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:58:57 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:58:57 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargerStatusReason: unknown
[lp-1  ] DEBUG 2025/02/05 10:58:57 pv charge current: 0.115A = 0A + 0.115A (-26W @ 1p)
[circuit-main] TRACE 2025/02/05 10:58:57 validate power: 98.64W + (1525W -> 1380W) <= 14900W ok
[site  ] TRACE 2025/02/05 10:58:57 telemetry: charge: Δ0/0Wh @ 1525W
[site  ] TRACE 2025/02/05 10:58:57 POST https://api.evcc.io/v1/charge
[cache ] TRACE 2025/02/05 10:58:57 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:58:57 lp-1/mode: pv
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planOverrun: 0s
[cache ] TRACE 2025/02/05 10:58:57 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:58:57 greenShareLoadpoints: 0.9353180327868853
[cache ] TRACE 2025/02/05 10:58:57 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:58:57 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:58:57 tariffPriceLoadpoints: 0.020672524590163926
[cache ] TRACE 2025/02/05 10:58:57 tariffCo2Loadpoints: 30.206478688524566
[mqtt  ] TRACE 2025/02/05 10:58:57 recv evcc/loadpoints/1/enableThreshold/set: '-384'
[lp-1  ] DEBUG 2025/02/05 10:58:57 set enable threshold: -384
[mqtt  ] TRACE 2025/02/05 10:58:57 send evcc/loadpoints/1/enableThreshold/set: ''
[mqtt  ] TRACE 2025/02/05 10:58:57 recv evcc/loadpoints/1/enableThreshold/set: ''
[mqtt  ] TRACE 2025/02/05 10:58:57 recv evcc/loadpoints/1/disableThreshold/set: '1318'
[lp-1  ] DEBUG 2025/02/05 10:58:57 set disable threshold: 1318
[mqtt  ] TRACE 2025/02/05 10:58:57 send evcc/loadpoints/1/disableThreshold/set: ''
[site  ] DEBUG 2025/02/05 10:58:57 ----
[cache ] TRACE 2025/02/05 10:58:57 lp-1/enableThreshold: -384
[cache ] TRACE 2025/02/05 10:58:57 lp-1/disableThreshold: 1318
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge power: 1525W
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargePower: 1525
[mqtt  ] TRACE 2025/02/05 10:58:57 recv evcc/loadpoints/1/disableThreshold/set: ''
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge currents: [6.2 0 0]A
[circuit-main] DEBUG 2025/02/05 10:58:57 power: 98.64W
[circuit-main] DEBUG 2025/02/05 10:58:57 current: 0A
[site  ] DEBUG 2025/02/05 10:58:57 pv 1 power: 3549W
[cache ] TRACE 2025/02/05 10:58:57 circuits: map[main:{98.64 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:58:57 batteryGridChargeActive: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:58:57 pvPower: 3549.4
[cache ] TRACE 2025/02/05 10:58:57 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:58:57 pv: [{3549.4 20384.2 [] [] 0 <nil> <nil> <nil>}]
[site  ] DEBUG 2025/02/05 10:58:57 grid power: 99W
[site  ] DEBUG 2025/02/05 10:58:57 site power: -26W
[cache ] TRACE 2025/02/05 10:58:57 grid: {98.64 12822.3157 [] [] 0 <nil> <nil> <nil>}
[cache ] TRACE 2025/02/05 10:58:57 homePower: 2123.04
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge voltages: [246 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:58:57 detected connected phases: 1p
[cache ] TRACE 2025/02/05 10:58:57 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeVoltages: [245.7 0 0]
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargedEnergy: 0
[lp-1  ] DEBUG 2025/02/05 10:58:57 charge total import: 324.409kWh
[lp-1  ] DEBUG 2025/02/05 10:58:57 charger status: B
[lp-1  ] DEBUG 2025/02/05 10:58:57 pv charge current: 0.115A = 0A + 0.115A (-26W @ 1p)
[circuit-main] TRACE 2025/02/05 10:58:57 validate power: 98.64W + (1525W -> 1380W) <= 14900W ok
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargeTotalImport: 324.409
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanId: 2
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:58:57 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:58:57 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:58:57 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:58:57 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:58:57 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:58:57 lp-1/mode: pv
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:58:57 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:58:57 greenShareLoadpoints: 0.9353180327868853
[cache ] TRACE 2025/02/05 10:58:57 lp-1/planOverrun: 0s
[cache ] TRACE 2025/02/05 10:58:57 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:58:57 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:58:57 tariffPriceLoadpoints: 0.020672524590163926
[cache ] TRACE 2025/02/05 10:58:57 tariffCo2Loadpoints: 30.206478688524566
[site  ] TRACE 2025/02/05 10:58:57 {"instanceId":"71a3bc903b23c9329a97972f53e2d339881a854b492d4e58510cdf42729a5e5c","chargePower":1525,"greenPower":1426.3600000000001,"chargeEnergy":0,"greenEnergy":0}
--
{"status":"ok"}
[mqtt  ] TRACE 2025/02/05 10:58:57 recv shellies/house-energy-monitor/emeter/0/power: '114.53'
[mqtt  ] TRACE 2025/02/05 10:58:57 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[mqtt  ] TRACE 2025/02/05 10:58:58 recv shellies/house-energy-monitor/emeter/0/power: '114.53'
[mqtt  ] TRACE 2025/02/05 10:58:59 recv shellies/house-energy-monitor/emeter/0/power: '172.76'
[mqtt  ] TRACE 2025/02/05 10:58:59 recv shellies/house-energy-monitor/emeter/0/total: '12822315.7'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv shellies/house-energy-monitor/emeter/0/power: '172.76'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv amber/co2: '467.0'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv energy/solar: '{"InverterStatus":1,"InputPower":3609.2,"PV1Voltage":214.6,"PV1InputCurrent":9.2,"PV1InputPower":1975.1,"PV2Voltage":231.4,"PV2InputCurrent":7.2,"PV2InputPower":1634.1,"OutputPower":3536.4,"GridFrequency":49.98,"L1ThreePhaseGridVoltage":248.2,"L1ThreePhaseGridOutputCurrent":15.7,"L1ThreePhaseGridOutputPower":3875.5,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52,"TemperatureInsideIPM":52,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3968}'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv shellies/house-energy-monitor/emeter/0/power: '119.69'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv shellies/house-energy-monitor/emeter/0/power: '119.69'
[mqtt  ] TRACE 2025/02/05 10:59:00 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:01 recv shellies/house-energy-monitor/emeter/0/power: '119.69'
[mqtt  ] TRACE 2025/02/05 10:59:04 recv shellies/house-energy-monitor/emeter/0/power: '165.89'
[mqtt  ] TRACE 2025/02/05 10:59:04 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:05 recv shellies/house-energy-monitor/emeter/0/power: '165.89'
[mqtt  ] TRACE 2025/02/05 10:59:05 recv energy/solar: '{"InverterStatus":1,"InputPower":3631.6,"PV1Voltage":218.5,"PV1InputCurrent":9.1,"PV1InputPower":1987.2,"PV2Voltage":235.3,"PV2InputCurrent":7.1,"PV2InputPower":1644.4,"OutputPower":3559,"GridFrequency":49.97,"L1ThreePhaseGridVoltage":248.2,"L1ThreePhaseGridOutputCurrent":15.6,"L1ThreePhaseGridOutputPower":3875.1,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52,"TemperatureInsideIPM":52,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3969}'
[mqtt  ] TRACE 2025/02/05 10:59:05 recv shellies/house-energy-monitor/emeter/0/power: '65.23'
[mqtt  ] TRACE 2025/02/05 10:59:05 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:06 recv shellies/house-energy-monitor/emeter/0/power: '65.23'
[mqtt  ] TRACE 2025/02/05 10:59:07 recv shellies/house-energy-monitor/emeter/0/power: '38.13'
[mqtt  ] TRACE 2025/02/05 10:59:07 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:08 recv shellies/house-energy-monitor/emeter/0/power: '38.13'
[mqtt  ] TRACE 2025/02/05 10:59:08 recv shellies/house-energy-monitor/emeter/0/power: '57.64'
[mqtt  ] TRACE 2025/02/05 10:59:08 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:09 recv shellies/house-energy-monitor/emeter/0/power: '57.64'
[mqtt  ] TRACE 2025/02/05 10:59:09 recv shellies/house-energy-monitor/emeter/0/power: '69.87'
[mqtt  ] TRACE 2025/02/05 10:59:09 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:10 recv shellies/house-energy-monitor/emeter/0/power: '69.87'
[mqtt  ] TRACE 2025/02/05 10:59:10 recv shellies/house-energy-monitor/emeter/0/power: '44.73'
[mqtt  ] TRACE 2025/02/05 10:59:10 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:11 recv shellies/house-energy-monitor/emeter/0/power: '44.73'
[mqtt  ] TRACE 2025/02/05 10:59:11 recv shellies/house-energy-monitor/emeter/0/power: '53.46'
[mqtt  ] TRACE 2025/02/05 10:59:11 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:11 recv shellies/house-energy-monitor/emeter/0/power: '53.46'
[mqtt  ] TRACE 2025/02/05 10:59:12 recv energy/solar: '{"InverterStatus":1,"InputPower":3629.6,"PV1Voltage":217.1,"PV1InputCurrent":9,"PV1InputPower":1988.7,"PV2Voltage":232.4,"PV2InputCurrent":7.1,"PV2InputPower":1640.9,"OutputPower":3557.2,"GridFrequency":49.98,"L1ThreePhaseGridVoltage":247.7,"L1ThreePhaseGridOutputCurrent":15.6,"L1ThreePhaseGridOutputPower":3842.6,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52,"TemperatureInsideIPM":52,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3971}'
[mqtt  ] TRACE 2025/02/05 10:59:13 recv shellies/house-energy-monitor/emeter/0/power: '34.15'
[mqtt  ] TRACE 2025/02/05 10:59:13 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:14 recv shellies/house-energy-monitor/emeter/0/power: '34.15'
[mqtt  ] TRACE 2025/02/05 10:59:15 recv shellies/house-energy-monitor/emeter/0/power: '65.07'
[mqtt  ] TRACE 2025/02/05 10:59:15 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:16 recv shellies/house-energy-monitor/emeter/0/power: '65.07'
[mqtt  ] TRACE 2025/02/05 10:59:17 recv energy/solar: '{"InverterStatus":1,"InputPower":3628.9,"PV1Voltage":222.4,"PV1InputCurrent":8.8,"PV1InputPower":1992.9,"PV2Voltage":237.5,"PV2InputCurrent":6.9,"PV2InputPower":1636,"OutputPower":3556.7,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247.7,"L1ThreePhaseGridOutputCurrent":15.5,"L1ThreePhaseGridOutputPower":3810.7,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.1,"TemperatureInsideIPM":52.1,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3973}'
[mqtt  ] TRACE 2025/02/05 10:59:17 recv shellies/house-energy-monitor/emeter/0/power: '108.51'
[mqtt  ] TRACE 2025/02/05 10:59:17 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:18 recv shellies/house-energy-monitor/emeter/0/power: '108.51'
[mqtt  ] TRACE 2025/02/05 10:59:18 recv shellies/house-energy-monitor/emeter/0/power: '120.31'
[mqtt  ] TRACE 2025/02/05 10:59:18 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:19 recv shellies/house-energy-monitor/emeter/0/power: '120.31'
[mqtt  ] TRACE 2025/02/05 10:59:19 recv shellies/house-energy-monitor/emeter/0/power: '127.70'
[mqtt  ] TRACE 2025/02/05 10:59:19 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:20 recv shellies/house-energy-monitor/emeter/0/power: '127.7'
[mqtt  ] TRACE 2025/02/05 10:59:21 recv shellies/house-energy-monitor/emeter/0/power: '45.66'
[mqtt  ] TRACE 2025/02/05 10:59:21 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:22 recv shellies/house-energy-monitor/emeter/0/power: '45.66'
[mqtt  ] TRACE 2025/02/05 10:59:22 recv energy/solar: '{"InverterStatus":1,"InputPower":3628.9,"PV1Voltage":220.1,"PV1InputCurrent":9.1,"PV1InputPower":1994.7,"PV2Voltage":235.3,"PV2InputCurrent":7.1,"PV2InputPower":1634.2,"OutputPower":3556.4,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247.7,"L1ThreePhaseGridOutputCurrent":15.6,"L1ThreePhaseGridOutputPower":3825.9,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.1,"TemperatureInsideIPM":52.1,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3975}'
[mqtt  ] TRACE 2025/02/05 10:59:22 recv shellies/house-energy-monitor/emeter/0/power: '43.27'
[mqtt  ] TRACE 2025/02/05 10:59:22 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:22 recv shellies/house-energy-monitor/emeter/0/power: '43.27'
[mqtt  ] TRACE 2025/02/05 10:59:23 recv shellies/house-energy-monitor/emeter/0/power: '68.41'
[mqtt  ] TRACE 2025/02/05 10:59:23 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:24 recv shellies/house-energy-monitor/emeter/0/power: '68.41'
[mqtt  ] TRACE 2025/02/05 10:59:24 recv shellies/house-energy-monitor/emeter/0/power: '60.34'
[mqtt  ] TRACE 2025/02/05 10:59:24 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:25 recv shellies/house-energy-monitor/emeter/0/power: '60.34'
[mqtt  ] TRACE 2025/02/05 10:59:25 recv energy/solar: '{"InverterStatus":1,"InputPower":3628.2,"PV1Voltage":218.3,"PV1InputCurrent":9.1,"PV1InputPower":1993.6,"PV2Voltage":233.6,"PV2InputCurrent":7.1,"PV2InputPower":1634.6,"OutputPower":3555.7,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247.6,"L1ThreePhaseGridOutputCurrent":15.5,"L1ThreePhaseGridOutputPower":3817.7,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.2,"TemperatureInsideIPM":52.2,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3976}'
[mqtt  ] TRACE 2025/02/05 10:59:26 recv shellies/house-energy-monitor/emeter/0/power: '60.40'
[mqtt  ] TRACE 2025/02/05 10:59:26 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:27 recv shellies/house-energy-monitor/emeter/0/power: '60.4'
[site  ] DEBUG 2025/02/05 10:59:27 ----
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge power: 1519W
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargePower: 1519
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge currents: [6.2 0 0]A
[circuit-main] DEBUG 2025/02/05 10:59:27 power: 60.4W
[circuit-main] DEBUG 2025/02/05 10:59:27 current: 0A
[site  ] DEBUG 2025/02/05 10:59:27 grid power: 60W
[site  ] DEBUG 2025/02/05 10:59:27 pv 1 power: 3556W
[site  ] DEBUG 2025/02/05 10:59:27 site power: -65W
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:59:27 detected connected phases: 1p
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge total import: 324.422kWh
[cache ] TRACE 2025/02/05 10:59:27 circuits: map[main:{60.4 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:59:27 batteryGridChargeActive: false
[cache ] TRACE 2025/02/05 10:59:27 grid: {60.4 12822.3168 [] [] 0 <nil> <nil> <nil>}
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:59:27 pvPower: 3555.7
[cache ] TRACE 2025/02/05 10:59:27 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:59:27 pv: [{3555.7 20384.2 [] [] 0 <nil> <nil> <nil>}]
[cache ] TRACE 2025/02/05 10:59:27 homePower: 2097.1
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeVoltages: [245 0 0]
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeTotalImport: 324.422
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanId: 2
[lp-1  ] DEBUG 2025/02/05 10:59:27 charger status: B
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:27 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:27 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/mode: pv
[lp-1  ] DEBUG 2025/02/05 10:59:27 pv charge current: 0.281A = 0A + 0.281A (-65W @ 1p)
[circuit-main] TRACE 2025/02/05 10:59:27 validate power: 60.4W + (1519W -> 1380W) <= 14900W ok
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:59:27 greenShareLoadpoints: 0.9602369980250164
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planOverrun: 0s
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceLoadpoints: 0.016560895325872297
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Loadpoints: 18.569321922317346
[mqtt  ] TRACE 2025/02/05 10:59:27 recv evcc/loadpoints/1/enableThreshold/set: '-383'
[lp-1  ] DEBUG 2025/02/05 10:59:27 set enable threshold: -383
[mqtt  ] TRACE 2025/02/05 10:59:27 send evcc/loadpoints/1/enableThreshold/set: ''
[site  ] DEBUG 2025/02/05 10:59:27 ----
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge power: 1519W
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge currents: [6.2 0 0]A
[circuit-main] DEBUG 2025/02/05 10:59:27 power: 60.4W
[circuit-main] DEBUG 2025/02/05 10:59:27 current: 0A
[site  ] DEBUG 2025/02/05 10:59:27 grid power: 60W
[site  ] DEBUG 2025/02/05 10:59:27 pv 1 power: 3556W
[site  ] DEBUG 2025/02/05 10:59:27 site power: -65W
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:59:27 detected connected phases: 1p
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge total import: 324.422kWh
[lp-1  ] DEBUG 2025/02/05 10:59:27 charger status: B
[lp-1  ] DEBUG 2025/02/05 10:59:27 pv charge current: 0.281A = 0A + 0.281A (-65W @ 1p)
[circuit-main] TRACE 2025/02/05 10:59:27 validate power: 60.4W + (1519W -> 1380W) <= 14900W ok
[mqtt  ] TRACE 2025/02/05 10:59:27 recv evcc/loadpoints/1/enableThreshold/set: ''
[cache ] TRACE 2025/02/05 10:59:27 lp-1/enableThreshold: -383
[cache ] TRACE 2025/02/05 10:59:27 lp-1/disableThreshold: 1318
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargePower: 1519
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:59:27 circuits: map[main:{60.4 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:59:27 batteryGridChargeActive: false
[cache ] TRACE 2025/02/05 10:59:27 grid: {60.4 12822.3168 [] [] 0 <nil> <nil> <nil>}
[cache ] TRACE 2025/02/05 10:59:27 pvPower: 3555.7
[cache ] TRACE 2025/02/05 10:59:27 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:59:27 pv: [{3555.7 20384.2 [] [] 0 <nil> <nil> <nil>}]
[cache ] TRACE 2025/02/05 10:59:27 homePower: 2097.1
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeVoltages: [245 0 0]
[mqtt  ] TRACE 2025/02/05 10:59:27 recv evcc/loadpoints/1/disableThreshold/set: '1315'
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeTotalImport: 324.422
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanId: 2
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:27 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:27 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/mode: pv
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:59:27 greenShareLoadpoints: 0.9602369980250164
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planOverrun: 0s
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceLoadpoints: 0.016560895325872297
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Loadpoints: 18.569321922317346
[lp-1  ] DEBUG 2025/02/05 10:59:27 set disable threshold: 1315
[mqtt  ] TRACE 2025/02/05 10:59:27 send evcc/loadpoints/1/disableThreshold/set: ''
[site  ] DEBUG 2025/02/05 10:59:27 ----
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge power: 1519W
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge currents: [6.2 0 0]A
[circuit-main] DEBUG 2025/02/05 10:59:27 power: 60.4W
[circuit-main] DEBUG 2025/02/05 10:59:27 current: 0A
[cache ] TRACE 2025/02/05 10:59:27 lp-1/enableThreshold: -383
[cache ] TRACE 2025/02/05 10:59:27 lp-1/disableThreshold: 1315
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargePower: 1519
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:59:27 circuits: map[main:{60.4 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:59:27 batteryGridChargeActive: false
[site  ] DEBUG 2025/02/05 10:59:27 grid power: 60W
[site  ] DEBUG 2025/02/05 10:59:27 pv 1 power: 3556W
[site  ] DEBUG 2025/02/05 10:59:27 site power: -65W
[cache ] TRACE 2025/02/05 10:59:27 grid: {60.4 12822.3168 [] [] 0 <nil> <nil> <nil>}
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge voltages: [245 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:59:27 detected connected phases: 1p
[cache ] TRACE 2025/02/05 10:59:27 pvPower: 3555.7
[cache ] TRACE 2025/02/05 10:59:27 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:59:27 pv: [{3555.7 20384.2 [] [] 0 <nil> <nil> <nil>}]
[cache ] TRACE 2025/02/05 10:59:27 homePower: 2097.1
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeVoltages: [245 0 0]
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionEnergy: 0
[lp-1  ] DEBUG 2025/02/05 10:59:27 charge total import: 324.422kWh
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargedEnergy: 0
[lp-1  ] DEBUG 2025/02/05 10:59:27 charger status: B
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargeTotalImport: 324.422
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanId: 2
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:27 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:27 lp-1/vehicleWelcomeActive: false
[lp-1  ] DEBUG 2025/02/05 10:59:27 pv charge current: 0.281A = 0A + 0.281A (-65W @ 1p)
[circuit-main] TRACE 2025/02/05 10:59:27 validate power: 60.4W + (1519W -> 1380W) <= 14900W ok
[cache ] TRACE 2025/02/05 10:59:27 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:27 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:27 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:27 lp-1/mode: pv
[mqtt  ] TRACE 2025/02/05 10:59:27 recv evcc/loadpoints/1/disableThreshold/set: ''
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:27 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:59:27 greenShareLoadpoints: 0.9602369980250164
[cache ] TRACE 2025/02/05 10:59:27 lp-1/planOverrun: 0s
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:59:27 tariffPriceLoadpoints: 0.016560895325872297
[cache ] TRACE 2025/02/05 10:59:27 tariffCo2Loadpoints: 18.569321922317346
[mqtt  ] TRACE 2025/02/05 10:59:27 recv shellies/house-energy-monitor/emeter/0/power: '103.78'
[mqtt  ] TRACE 2025/02/05 10:59:27 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:28 recv shellies/house-energy-monitor/emeter/0/power: '103.78'
[mqtt  ] TRACE 2025/02/05 10:59:28 recv shellies/house-energy-monitor/emeter/0/power: '126.33'
[mqtt  ] TRACE 2025/02/05 10:59:28 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:29 recv shellies/house-energy-monitor/emeter/0/power: '126.33'
[mqtt  ] TRACE 2025/02/05 10:59:29 recv teslamate/cars/1/rated_battery_range_km: '203.82'
[mqtt  ] TRACE 2025/02/05 10:59:29 recv energy/solar: '{"InverterStatus":1,"InputPower":3618.9,"PV1Voltage":215.6,"PV1InputCurrent":9.2,"PV1InputPower":1991.7,"PV2Voltage":230.8,"PV2InputCurrent":7.1,"PV2InputPower":1627.2,"OutputPower":3546.1,"GridFrequency":49.98,"L1ThreePhaseGridVoltage":247.5,"L1ThreePhaseGridOutputCurrent":15.6,"L1ThreePhaseGridOutputPower":3828.3,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.2,"TemperatureInsideIPM":52.2,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3977}'
[mqtt  ] TRACE 2025/02/05 10:59:30 recv amber/co2: '467.0'
[mqtt  ] TRACE 2025/02/05 10:59:30 recv shellies/house-energy-monitor/emeter/0/power: '114.75'
[mqtt  ] TRACE 2025/02/05 10:59:30 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:30 recv shellies/house-energy-monitor/emeter/0/power: '114.75'
[mqtt  ] TRACE 2025/02/05 10:59:30 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:31 recv shellies/house-energy-monitor/emeter/0/power: '114.75'
[mqtt  ] TRACE 2025/02/05 10:59:31 recv shellies/house-energy-monitor/emeter/0/power: '90.01'
[mqtt  ] TRACE 2025/02/05 10:59:31 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:32 recv shellies/house-energy-monitor/emeter/0/power: '90.01'
[mqtt  ] TRACE 2025/02/05 10:59:32 recv shellies/house-energy-monitor/emeter/0/power: '94.54'
[mqtt  ] TRACE 2025/02/05 10:59:32 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:33 recv shellies/house-energy-monitor/emeter/0/power: '94.54'
[mqtt  ] TRACE 2025/02/05 10:59:34 recv shellies/house-energy-monitor/emeter/0/power: '13.64'
[mqtt  ] TRACE 2025/02/05 10:59:34 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:35 recv shellies/house-energy-monitor/emeter/0/power: '13.64'
[mqtt  ] TRACE 2025/02/05 10:59:35 recv energy/solar: '{"InverterStatus":1,"InputPower":3614.5,"PV1Voltage":215.4,"PV1InputCurrent":9.2,"PV1InputPower":1988.5,"PV2Voltage":233.9,"PV2InputCurrent":7,"PV2InputPower":1626,"OutputPower":3542.1,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247.5,"L1ThreePhaseGridOutputCurrent":15.5,"L1ThreePhaseGridOutputPower":3821.5,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.2,"TemperatureInsideIPM":52.2,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3978}'
[mqtt  ] TRACE 2025/02/05 10:59:37 recv shellies/house-energy-monitor/emeter/0/power: '80.57'
[mqtt  ] TRACE 2025/02/05 10:59:37 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:38 recv shellies/house-energy-monitor/emeter/0/power: '80.57'
[mqtt  ] TRACE 2025/02/05 10:59:39 recv shellies/house-energy-monitor/emeter/0/power: '63.84'
[mqtt  ] TRACE 2025/02/05 10:59:39 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:39 recv energy/solar: '{"InverterStatus":1,"InputPower":3613.9,"PV1Voltage":218.5,"PV1InputCurrent":9.1,"PV1InputPower":1996.4,"PV2Voltage":233.7,"PV2InputCurrent":7.1,"PV2InputPower":1617.5,"OutputPower":3541.6,"GridFrequency":49.98,"L1ThreePhaseGridVoltage":247.5,"L1ThreePhaseGridOutputCurrent":15.5,"L1ThreePhaseGridOutputPower":3810.6,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.3,"TemperatureInsideIPM":52.3,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3979}'
[mqtt  ] TRACE 2025/02/05 10:59:40 recv shellies/house-energy-monitor/emeter/0/power: '63.84'
[mqtt  ] TRACE 2025/02/05 10:59:40 recv shellies/house-energy-monitor/emeter/0/power: '60.12'
[mqtt  ] TRACE 2025/02/05 10:59:40 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:41 recv shellies/house-energy-monitor/emeter/0/power: '60.12'
[mqtt  ] TRACE 2025/02/05 10:59:42 recv shellies/house-energy-monitor/emeter/0/power: '56.95'
[mqtt  ] TRACE 2025/02/05 10:59:42 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:43 recv shellies/house-energy-monitor/emeter/0/power: '56.95'
[mqtt  ] TRACE 2025/02/05 10:59:43 recv shellies/house-energy-monitor/emeter/0/power: '50.47'
[mqtt  ] TRACE 2025/02/05 10:59:43 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:44 recv shellies/house-energy-monitor/emeter/0/power: '50.47'
[mqtt  ] TRACE 2025/02/05 10:59:44 recv shellies/house-energy-monitor/emeter/0/power: '65.86'
[mqtt  ] TRACE 2025/02/05 10:59:44 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:44 recv energy/solar: '{"InverterStatus":1,"InputPower":3604.4,"PV1Voltage":219.9,"PV1InputCurrent":9,"PV1InputPower":1991.2,"PV2Voltage":230.7,"PV2InputCurrent":7.1,"PV2InputPower":1613.2,"OutputPower":3532.7,"GridFrequency":49.97,"L1ThreePhaseGridVoltage":247.2,"L1ThreePhaseGridOutputCurrent":15.4,"L1ThreePhaseGridOutputPower":3781.7,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.3,"TemperatureInsideIPM":52.3,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3980}'
[mqtt  ] TRACE 2025/02/05 10:59:45 recv shellies/house-energy-monitor/emeter/0/power: '65.86'
[mqtt  ] TRACE 2025/02/05 10:59:45 recv shellies/house-energy-monitor/emeter/0/power: '50.41'
[mqtt  ] TRACE 2025/02/05 10:59:45 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:46 recv shellies/house-energy-monitor/emeter/0/power: '50.41'
[mqtt  ] TRACE 2025/02/05 10:59:46 recv shellies/house-energy-monitor/emeter/0/power: '55.24'
[mqtt  ] TRACE 2025/02/05 10:59:46 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:47 recv shellies/house-energy-monitor/emeter/0/power: '55.24'
[mqtt  ] TRACE 2025/02/05 10:59:48 recv shellies/house-energy-monitor/emeter/0/power: '69.87'
[mqtt  ] TRACE 2025/02/05 10:59:48 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:49 recv shellies/house-energy-monitor/emeter/0/power: '69.87'
[mqtt  ] TRACE 2025/02/05 10:59:50 recv energy/solar: '{"InverterStatus":1,"InputPower":3598.5,"PV1Voltage":219.8,"PV1InputCurrent":9.1,"PV1InputPower":1992.5,"PV2Voltage":231.1,"PV2InputCurrent":6.9,"PV2InputPower":1606,"OutputPower":3526.9,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247.3,"L1ThreePhaseGridOutputCurrent":15.4,"L1ThreePhaseGridOutputPower":3783.2,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.3,"TemperatureInsideIPM":52.3,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3981}'
[mqtt  ] TRACE 2025/02/05 10:59:50 recv shellies/house-energy-monitor/emeter/0/power: '14.11'
[mqtt  ] TRACE 2025/02/05 10:59:50 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:51 recv shellies/house-energy-monitor/emeter/0/power: '14.11'
[mqtt  ] TRACE 2025/02/05 10:59:52 recv shellies/house-energy-monitor/emeter/0/power: '21.95'
[mqtt  ] TRACE 2025/02/05 10:59:52 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[db    ] TRACE 2025/02/05 10:59:52 INSERT INTO `settings` (`key`,`value`) VALUES ("adminPassword","XXXXXX"),("jwtSecretKey","XXXXXXX"),("lp1.minCurrent","6"),("lp1.maxCurrent","32"),("lp1.phasesConfigured","1"),("lp1.mode","pv"),("lp1.smartCostLimit",""),("lp1.limitSoc","100"),("vehicle.zapdos.planTime","0001-01-01T00:00:00Z"),("vehicle.zapdos.planSoc","0"),("lp1.planEnergy","0"),("lp1.planTime","0001-01-01T00:00:00Z"),("residualPower","-125"),("telemetry","true"),("vehicle.zapdos.limitSoc","100"),("vehicle.zapdos.minSoc","10"),("vehicle.zapdos.repeatingPlans","[{""weekdays"":[1,2,3,4,5,6,0],""time"":""15:00"",""tz"":""Australia/Sydney"",""soc"":50,""active"":true},{""weekdays"":[6],""time"":""09:00"",""tz"":""Australia/Sydney"",""soc"":70,""active"":false}]"),("lp1.title","Garage"),("lp1.soc","{""poll"":{""mode"":""charging"",""interval"":3600000000000},""estimate"":null}"),("lp1.thresholds","{""enable"":{""delay"":180000000000,""threshold"":-383},""disable"":{""delay"":480000000000,""threshold"":1315}}") ON CONFLICT (`key`) DO UPDATE SET `value`=`excluded`.`value` 20 <nil>
[mqtt  ] TRACE 2025/02/05 10:59:53 recv shellies/house-energy-monitor/emeter/0/power: '21.95'
[mqtt  ] TRACE 2025/02/05 10:59:53 recv shellies/house-energy-monitor/emeter/0/power: '11.53'
[mqtt  ] TRACE 2025/02/05 10:59:53 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:54 recv shellies/house-energy-monitor/emeter/0/power: '11.53'
[mqtt  ] TRACE 2025/02/05 10:59:54 recv shellies/house-energy-monitor/emeter/0/power: '21.49'
[mqtt  ] TRACE 2025/02/05 10:59:54 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:55 recv energy/solar: '{"InverterStatus":1,"InputPower":3593.5,"PV1Voltage":220.1,"PV1InputCurrent":9,"PV1InputPower":1990.5,"PV2Voltage":230.9,"PV2InputCurrent":7,"PV2InputPower":1603,"OutputPower":3521.9,"GridFrequency":49.99,"L1ThreePhaseGridVoltage":247,"L1ThreePhaseGridOutputCurrent":15.3,"L1ThreePhaseGridOutputPower":3758,"L2ThreePhaseGridVoltage":0,"L2ThreePhaseGridOutputCurrent":0,"L2ThreePhaseGridOutputPower":0,"L3ThreePhaseGridVoltage":0,"L3ThreePhaseGridOutputCurrent":0,"L3ThreePhaseGridOutputPower":0,"TodayGenerateEnergy":5,"TotalGenerateEnergy":20384.2,"TWorkTimeTotal":2.147483647e7,"PV1EnergyToday":2.6,"PV1EnergyTotal":10462.8,"PV2EnergyToday":2.5,"PV2EnergyTotal":10262.2,"PVEnergyTotal":20725,"InverterTemperature":52.3,"TemperatureInsideIPM":52.3,"BoostTemperature":0,"DischargePower":0,"ChargePower":0,"BatteryVoltage":0,"SOC":0,"ACPowerToUser":0,"ACPowerToUserTotal":0,"ACPowerToGrid":0,"ACPowerToGridTotal":0,"INVPowerToLocalLoad":0,"INVPowerToLocalLoadTotal":0,"BatteryTemperature":0,"BatteryState":0,"EnergyToUserToday":0,"EnergyToUserTotal":0,"EnergyToGridToday":0,"EnergyToGridTotal":0,"DischargeEnergyToday":0,"DischargeEnergyTotal":0,"ChargeEnergyToday":0,"ChargeEnergyTotal":0,"LocalLoadEnergyToday":0,"LocalLoadEnergyTotal":0,"Mac":"A8:48:FA:F6:A9:E6","Cnt":3982}'
[mqtt  ] TRACE 2025/02/05 10:59:55 recv shellies/house-energy-monitor/emeter/0/power: '21.49'
[mqtt  ] TRACE 2025/02/05 10:59:56 recv shellies/house-energy-monitor/emeter/0/power: '35.93'
[mqtt  ] TRACE 2025/02/05 10:59:56 recv shellies/house-energy-monitor/emeter/0/total: '12822316.8'
[mqtt  ] TRACE 2025/02/05 10:59:57 recv shellies/house-energy-monitor/emeter/0/power: '35.93'
[site  ] DEBUG 2025/02/05 10:59:57 ----
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge power: 1517W
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargePower: 1517
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge currents: [6.2 0 0]A
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeCurrents: [6.2 0 0]
[circuit-main] DEBUG 2025/02/05 10:59:57 power: 35.93W
[circuit-main] DEBUG 2025/02/05 10:59:57 current: 0A
[cache ] TRACE 2025/02/05 10:59:57 circuits: map[main:{35.93 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:59:57 batteryGridChargeActive: false
[site  ] DEBUG 2025/02/05 10:59:57 grid power: 36W
[cache ] TRACE 2025/02/05 10:59:57 grid: {35.93 12822.3168 [] [] 0 <nil> <nil> <nil>}
[site  ] DEBUG 2025/02/05 10:59:57 pv 1 power: 3522W
[site  ] DEBUG 2025/02/05 10:59:57 site power: -89W
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge voltages: [244 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:59:57 detected connected phases: 1p
[cache ] TRACE 2025/02/05 10:59:57 pvPower: 3521.9
[cache ] TRACE 2025/02/05 10:59:57 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:59:57 pv: [{3521.9 20384.2 [] [] 0 <nil> <nil> <nil>}]
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge total import: 324.435kWh
[lp-1  ] DEBUG 2025/02/05 10:59:57 charger status: B
[lp-1  ] DEBUG 2025/02/05 10:59:57 pv charge current: 0.387A = 0A + 0.387A (-89W @ 1p)
[circuit-main] TRACE 2025/02/05 10:59:57 validate power: 35.93W + (1517W -> 1380W) <= 14900W ok
[cache ] TRACE 2025/02/05 10:59:57 homePower: 2040.83
[cache ] TRACE 2025/02/05 10:59:57 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeVoltages: [244.4 0 0]
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeCurrent: 6
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeTotalImport: 324.435
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanId: 2
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:57 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:57 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/mode: pv
[cache ] TRACE 2025/02/05 10:59:57 lp-1/planProjectedStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:57 lp-1/planProjectedEnd: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:57 greenShareHome: 1
[cache ] TRACE 2025/02/05 10:59:57 greenShareLoadpoints: 0.9763150955833884
[cache ] TRACE 2025/02/05 10:59:57 lp-1/planOverrun: 0s
[site  ] TRACE 2025/02/05 10:59:57 telemetry: charge: Δ0/0Wh @ 1517W
[cache ] TRACE 2025/02/05 10:59:57 tariffPriceHome: 0.01
[cache ] TRACE 2025/02/05 10:59:57 tariffCo2Home: 0
[cache ] TRACE 2025/02/05 10:59:57 tariffPriceLoadpoints: 0.013908009228740921
[cache ] TRACE 2025/02/05 10:59:57 tariffCo2Loadpoints: 11.060850362557636
[mqtt  ] TRACE 2025/02/05 10:59:57 recv evcc/loadpoints/1/enableThreshold/set: '-382'
[lp-1  ] DEBUG 2025/02/05 10:59:57 set enable threshold: -382
[mqtt  ] TRACE 2025/02/05 10:59:57 send evcc/loadpoints/1/enableThreshold/set: ''
[site  ] DEBUG 2025/02/05 10:59:57 ----
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge power: 1517W
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge currents: [6.2 0 0]A
[circuit-main] DEBUG 2025/02/05 10:59:57 power: 35.93W
[circuit-main] DEBUG 2025/02/05 10:59:57 current: 0A
[cache ] TRACE 2025/02/05 10:59:57 lp-1/enableThreshold: -382
[cache ] TRACE 2025/02/05 10:59:57 lp-1/disableThreshold: 1315
[site  ] DEBUG 2025/02/05 10:59:57 grid power: 36W
[mqtt  ] TRACE 2025/02/05 10:59:57 recv evcc/loadpoints/1/enableThreshold/set: ''
[site  ] DEBUG 2025/02/05 10:59:57 pv 1 power: 3522W
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargePower: 1517
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeCurrents: [6.2 0 0]
[cache ] TRACE 2025/02/05 10:59:57 circuits: map[main:{35.93 <nil> 14900 0}]
[cache ] TRACE 2025/02/05 10:59:57 batteryGridChargeActive: false
[cache ] TRACE 2025/02/05 10:59:57 grid: {35.93 12822.3168 [] [] 0 <nil> <nil> <nil>}
[cache ] TRACE 2025/02/05 10:59:57 pvPower: 3521.9
[cache ] TRACE 2025/02/05 10:59:57 pvEnergy: 20384.2
[cache ] TRACE 2025/02/05 10:59:57 pv: [{3521.9 20384.2 [] [] 0 <nil> <nil> <nil>}]
[site  ] DEBUG 2025/02/05 10:59:57 site power: -89W
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge voltages: [244 0 0]V
[lp-1  ] DEBUG 2025/02/05 10:59:57 detected connected phases: 1p
[cache ] TRACE 2025/02/05 10:59:57 homePower: 2040.83
[cache ] TRACE 2025/02/05 10:59:57 lp-1/smartCostActive: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeVoltages: [244.4 0 0]
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeCurrent: 6
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge total import: 324.435kWh
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeTotalImport: 324.435
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanId: 2
[lp-1  ] DEBUG 2025/02/05 10:59:57 charger status: B
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:57 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:57 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/mode: pv
[mqtt  ] TRACE 2025/02/05 10:59:57 recv evcc/loadpoints/1/disableThreshold/set: '1312'
[site  ] TRACE 2025/02/05 10:59:57 POST https://api.evcc.io/v1/charge
[site  ] TRACE 2025/02/05 10:59:57 {"instanceId":"XXXXXXXXX","chargePower":1517,"greenPower":1481.0700000000002,"chargeEnergy":0,"greenEnergy":0}
--
{"status":"ok"}

What type of operating system or environment does evcc run on?

Docker container

External automation

  • I have made sure that no external automation like HomeAssistant or Node-RED is active or accessing any of the mentioned devices when this issue occurs.

Nightly build

  • I have verified that the issue is reproducible with the latest nightly build

Version

0.133.0

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 5, 2025

The log looks cut off but here is the end of it. there is no activity apart from ocpp

[cache ] TRACE 2025/02/05 10:59:57 lp-1/smartCostNextStart: 0001-01-01 00:00:00 +0000 UTC
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeVoltages: [244.4 0 0]
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeCurrent: 6
[lp-1  ] DEBUG 2025/02/05 10:59:57 charge total import: 324.435kWh
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionSolarPercentage: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPricePerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionPrice: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/sessionCo2PerKWh: <nil>
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargedEnergy: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeDuration: 0s
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargeTotalImport: 324.435
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePriority: 0
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanId: 2
[lp-1  ] DEBUG 2025/02/05 10:59:57 charger status: B
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanTime: 2025-02-05 15:00:00 +1100 AEDT
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectivePlanSoc: 50
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMinCurrent: 6
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveMaxCurrent: 32
[cache ] TRACE 2025/02/05 10:59:57 lp-1/effectiveLimitSoc: 100
[cache ] TRACE 2025/02/05 10:59:57 lp-1/vehicleWelcomeActive: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/connected: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/charging: false
[cache ] TRACE 2025/02/05 10:59:57 lp-1/chargerStatusReason: unknown
[cache ] TRACE 2025/02/05 10:59:57 lp-1/enabled: true
[cache ] TRACE 2025/02/05 10:59:57 lp-1/mode: pv
[mqtt  ] TRACE 2025/02/05 10:59:57 recv evcc/loadpoints/1/disableThreshold/set: '1312'
[site  ] TRACE 2025/02/05 10:59:57 POST https://api.evcc.io/v1/charge
[site  ] TRACE 2025/02/05 10:59:57 {"instanceId":"XXXXXXXXX","chargePower":1517,"greenPower":1481.0700000000002,"chargeEnergy":0,"greenEnergy":0}
--
{"status":"ok"}
[ocpp  ] TRACE 2025/02/05 11:00:01 recv SN10052307203612: [2,"176301","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:00Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324435.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:01 send SN10052307203612: [3,"176301",{}]
[ocpp  ] TRACE 2025/02/05 11:00:02 recv SN10052307203612: [2,"176302","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":20,\"AMBTemp\":410,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:02 send SN10052307203612: [3,"176302",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:00:11 recv SN10052307203612: [2,"176303","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:10Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324448.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:11 send SN10052307203612: [3,"176303",{}]
[ocpp  ] TRACE 2025/02/05 11:00:12 recv SN10052307203612: [2,"176304","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":20,\"AMBTemp\":410,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:12 send SN10052307203612: [3,"176304",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:00:18 recv SN10052307203612: [2,"176305","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:00:18 send SN10052307203612: [3,"176305",{"currentTime":"2025-02-05T00:00:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:00:21 recv SN10052307203612: [2,"176306","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:20Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324448.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:21 send SN10052307203612: [3,"176306",{}]
[ocpp  ] TRACE 2025/02/05 11:00:22 recv SN10052307203612: [2,"176307","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":411,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:22 send SN10052307203612: [3,"176307",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:00:31 recv SN10052307203612: [2,"176308","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:30Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324448.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:31 send SN10052307203612: [3,"176308",{}]
[ocpp  ] TRACE 2025/02/05 11:00:32 recv SN10052307203612: [2,"176309","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":20,\"AMBTemp\":411,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:32 send SN10052307203612: [3,"176309",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:00:41 recv SN10052307203612: [2,"176310","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:40Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324461.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:41 send SN10052307203612: [3,"176310",{}]
[ocpp  ] TRACE 2025/02/05 11:00:42 recv SN10052307203612: [2,"176311","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":20,\"AMBTemp\":411,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:42 send SN10052307203612: [3,"176311",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:00:51 recv SN10052307203612: [2,"176312","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:00:50Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324461.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1515.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:00:51 send SN10052307203612: [3,"176312",{}]
[ocpp  ] TRACE 2025/02/05 11:00:52 recv SN10052307203612: [2,"176313","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":20,\"AMBTemp\":412,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:00:52 send SN10052307203612: [3,"176313",{"status":"Accepted"}]
[db    ] TRACE 2025/02/05 11:00:52 INSERT INTO `settings` (`key`,`value`) VALUES ("adminPassword","XXXXXXXXX"),("jwtSecretKey","XXXXXXXX"),("lp1.minCurrent","6"),("lp1.maxCurrent","32"),("lp1.phasesConfigured","1"),("lp1.mode","pv"),("lp1.smartCostLimit",""),("lp1.limitSoc","100"),("vehicle.zapdos.planTime","0001-01-01T00:00:00Z"),("vehicle.zapdos.planSoc","0"),("lp1.planEnergy","0"),("lp1.planTime","0001-01-01T00:00:00Z"),("residualPower","-125"),("telemetry","true"),("vehicle.zapdos.limitSoc","100"),("vehicle.zapdos.minSoc","10"),("vehicle.zapdos.repeatingPlans","[{""weekdays"":[1,2,3,4,5,6,0],""time"":""15:00"",""tz"":""Australia/Sydney"",""soc"":50,""active"":true},{""weekdays"":[6],""time"":""09:00"",""tz"":""Australia/Sydney"",""soc"":70,""active"":false}]"),("lp1.title","Garage"),("lp1.soc","{""poll"":{""mode"":""charging"",""interval"":3600000000000},""estimate"":null}"),("lp1.thresholds","{""enable"":{""delay"":180000000000,""threshold"":-382},""disable"":{""delay"":480000000000,""threshold"":1315}}") ON CONFLICT (`key`) DO UPDATE SET `value`=`excluded`.`value` 20 <nil>
[ocpp  ] TRACE 2025/02/05 11:01:01 recv SN10052307203612: [2,"176314","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:00Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324461.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:01 send SN10052307203612: [3,"176314",{}]
[ocpp  ] TRACE 2025/02/05 11:01:02 recv SN10052307203612: [2,"176315","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2045,\"leakcurr\":10,\"AMBTemp\":412,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:02 send SN10052307203612: [3,"176315",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:11 send SN10052307203612: [2,"121486945","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:01:11 recv SN10052307203612: [2,"176316","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:10Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324474.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:11 send SN10052307203612: [3,"176316",{}]
[ocpp  ] TRACE 2025/02/05 11:01:12 recv SN10052307203612: [3,"121486945",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:12 recv SN10052307203612: [2,"176317","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324474.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:12 send SN10052307203612: [3,"176317",{}]
[ocpp  ] TRACE 2025/02/05 11:01:12 recv SN10052307203612: [2,"176318","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":412,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:12 send SN10052307203612: [3,"176318",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:13 recv SN10052307203612: [2,"176319","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":412,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:13 send SN10052307203612: [3,"176319",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:18 recv SN10052307203612: [2,"176320","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:01:18 send SN10052307203612: [3,"176320",{"currentTime":"2025-02-05T00:01:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:01:21 send SN10052307203612: [2,"1973335506","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:01:22 recv SN10052307203612: [2,"176321","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:20Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324474.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:22 send SN10052307203612: [3,"176321",{}]
[ocpp  ] TRACE 2025/02/05 11:01:22 recv SN10052307203612: [3,"1973335506",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:23 recv SN10052307203612: [2,"176322","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:22Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324474.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:23 send SN10052307203612: [3,"176322",{}]
[ocpp  ] TRACE 2025/02/05 11:01:23 recv SN10052307203612: [2,"176323","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":413,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:23 send SN10052307203612: [3,"176323",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:23 recv SN10052307203612: [2,"176324","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":618,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":413,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:23 send SN10052307203612: [3,"176324",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:31 recv SN10052307203612: [2,"176325","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:30Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324474.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:31 send SN10052307203612: [3,"176325",{}]
[ocpp  ] TRACE 2025/02/05 11:01:32 recv SN10052307203612: [2,"176326","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":413,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:32 send SN10052307203612: [3,"176326",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:41 recv SN10052307203612: [2,"176327","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:40Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324487.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:41 send SN10052307203612: [3,"176327",{}]
[ocpp  ] TRACE 2025/02/05 11:01:42 recv SN10052307203612: [2,"176328","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":413,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:42 send SN10052307203612: [3,"176328",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:01:51 recv SN10052307203612: [2,"176329","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:01:50Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324487.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:01:51 send SN10052307203612: [3,"176329",{}]
[ocpp  ] TRACE 2025/02/05 11:01:52 recv SN10052307203612: [2,"176330","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:01:52 send SN10052307203612: [3,"176330",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:01 send SN10052307203612: [2,"1055253631","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:01 recv SN10052307203612: [2,"176331","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:00Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.6","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324487.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:01 send SN10052307203612: [3,"176331",{}]
[ocpp  ] TRACE 2025/02/05 11:02:02 recv SN10052307203612: [3,"1055253631",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:02 recv SN10052307203612: [2,"176332","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324487.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:02 send SN10052307203612: [3,"176332",{}]
[ocpp  ] TRACE 2025/02/05 11:02:02 recv SN10052307203612: [2,"176333","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:02 send SN10052307203612: [3,"176333",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:03 recv SN10052307203612: [2,"176334","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:03 send SN10052307203612: [3,"176334",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:11 send SN10052307203612: [2,"1228312082","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:12 recv SN10052307203612: [2,"176335","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:10Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:13 recv SN10052307203612: [3,"1228312082",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:13 send SN10052307203612: [3,"176335",{}]
[ocpp  ] TRACE 2025/02/05 11:02:13 recv SN10052307203612: [2,"176336","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:13 send SN10052307203612: [3,"176336",{}]
[ocpp  ] TRACE 2025/02/05 11:02:13 recv SN10052307203612: [2,"176337","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:13 send SN10052307203612: [3,"176337",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:14 recv SN10052307203612: [2,"176338","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":10,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:14 send SN10052307203612: [3,"176338",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:18 recv SN10052307203612: [2,"176339","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:02:18 send SN10052307203612: [3,"176339",{"currentTime":"2025-02-05T00:02:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:02:21 send SN10052307203612: [2,"2605968694","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:21 recv SN10052307203612: [2,"176340","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:20Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1521.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:21 send SN10052307203612: [3,"176340",{}]
[ocpp  ] TRACE 2025/02/05 11:02:22 recv SN10052307203612: [3,"2605968694",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:22 recv SN10052307203612: [2,"176341","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.0","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1521.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:22 send SN10052307203612: [3,"176341",{}]
[ocpp  ] TRACE 2025/02/05 11:02:22 recv SN10052307203612: [2,"176342","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:22 send SN10052307203612: [3,"176342",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:23 recv SN10052307203612: [2,"176343","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":415,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:23 send SN10052307203612: [3,"176343",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:31 send SN10052307203612: [2,"635127284","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:31 recv SN10052307203612: [2,"176344","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:30Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1521.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:31 send SN10052307203612: [3,"176344",{}]
[ocpp  ] TRACE 2025/02/05 11:02:32 recv SN10052307203612: [3,"635127284",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:32 recv SN10052307203612: [2,"176345","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.0","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324500.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1521.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:32 send SN10052307203612: [3,"176345",{}]
[ocpp  ] TRACE 2025/02/05 11:02:32 recv SN10052307203612: [2,"176346","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:32 send SN10052307203612: [3,"176346",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:33 recv SN10052307203612: [2,"176347","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:33 send SN10052307203612: [3,"176347",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:41 send SN10052307203612: [2,"3937805466","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:41 recv SN10052307203612: [2,"176348","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.6","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1511.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:41 send SN10052307203612: [3,"176348",{}]
[ocpp  ] TRACE 2025/02/05 11:02:42 recv SN10052307203612: [3,"3937805466",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:42 recv SN10052307203612: [2,"176349","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1511.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:42 send SN10052307203612: [3,"176349",{}]
[ocpp  ] TRACE 2025/02/05 11:02:42 recv SN10052307203612: [2,"176350","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:42 send SN10052307203612: [3,"176350",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:43 recv SN10052307203612: [2,"176351","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:43 send SN10052307203612: [3,"176351",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:51 send SN10052307203612: [2,"3668414773","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:02:51 recv SN10052307203612: [2,"176352","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:50Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.2","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1522.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:51 send SN10052307203612: [3,"176352",{}]
[ocpp  ] TRACE 2025/02/05 11:02:52 recv SN10052307203612: [3,"3668414773",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:52 recv SN10052307203612: [2,"176353","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:02:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"245.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1522.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:02:52 send SN10052307203612: [3,"176353",{}]
[ocpp  ] TRACE 2025/02/05 11:02:52 recv SN10052307203612: [2,"176354","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:52 send SN10052307203612: [3,"176354",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:02:53 recv SN10052307203612: [2,"176355","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":416,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:02:53 send SN10052307203612: [3,"176355",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:01 send SN10052307203612: [2,"3105662401","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:03:01 recv SN10052307203612: [2,"176356","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:00Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:01 send SN10052307203612: [3,"176356",{}]
[ocpp  ] TRACE 2025/02/05 11:03:02 recv SN10052307203612: [3,"3105662401",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:02 recv SN10052307203612: [2,"176357","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324513.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:02 send SN10052307203612: [3,"176357",{}]
[ocpp  ] TRACE 2025/02/05 11:03:02 recv SN10052307203612: [2,"176358","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:02 send SN10052307203612: [3,"176358",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:03 recv SN10052307203612: [2,"176359","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:03 send SN10052307203612: [3,"176359",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:11 send SN10052307203612: [2,"1465392881","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:03:11 recv SN10052307203612: [2,"176360","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:10Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324526.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:11 send SN10052307203612: [3,"176360",{}]
[ocpp  ] TRACE 2025/02/05 11:03:12 recv SN10052307203612: [3,"1465392881",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:12 recv SN10052307203612: [2,"176361","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324526.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1520.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:12 send SN10052307203612: [3,"176361",{}]
[ocpp  ] TRACE 2025/02/05 11:03:12 recv SN10052307203612: [2,"176362","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:12 send SN10052307203612: [3,"176362",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:13 recv SN10052307203612: [2,"176363","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:13 send SN10052307203612: [3,"176363",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:18 recv SN10052307203612: [2,"176364","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:03:18 send SN10052307203612: [3,"176364",{"currentTime":"2025-02-05T00:03:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:03:21 send SN10052307203612: [2,"1491242531","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:03:21 recv SN10052307203612: [2,"176365","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:20Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324526.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1513.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:21 send SN10052307203612: [3,"176365",{}]
[ocpp  ] TRACE 2025/02/05 11:03:22 recv SN10052307203612: [3,"1491242531",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:22 recv SN10052307203612: [2,"176366","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.9","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324526.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1513.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:22 send SN10052307203612: [3,"176366",{}]
[ocpp  ] TRACE 2025/02/05 11:03:24 recv SN10052307203612: [2,"176367","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:24 recv SN10052307203612: [2,"176368","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:24 send SN10052307203612: [3,"176367",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:24 send SN10052307203612: [3,"176368",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:31 recv SN10052307203612: [2,"176369","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:30Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.5","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324526.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:31 send SN10052307203612: [3,"176369",{}]
[ocpp  ] TRACE 2025/02/05 11:03:32 recv SN10052307203612: [2,"176370","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":417,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:32 send SN10052307203612: [3,"176370",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:41 send SN10052307203612: [2,"1754884650","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:03:41 recv SN10052307203612: [2,"176371","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:40Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1516.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:41 send SN10052307203612: [3,"176371",{}]
[ocpp  ] TRACE 2025/02/05 11:03:42 recv SN10052307203612: [3,"1754884650",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:42 recv SN10052307203612: [2,"176372","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1516.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:42 send SN10052307203612: [3,"176372",{}]
[ocpp  ] TRACE 2025/02/05 11:03:42 recv SN10052307203612: [2,"176373","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:42 send SN10052307203612: [3,"176373",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:43 recv SN10052307203612: [2,"176374","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2046,\"leakcurr\":20,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:43 send SN10052307203612: [3,"176374",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:51 send SN10052307203612: [2,"417138468","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:03:51 recv SN10052307203612: [2,"176375","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:51 send SN10052307203612: [3,"176375",{}]
[ocpp  ] TRACE 2025/02/05 11:03:52 recv SN10052307203612: [3,"417138468",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:52 recv SN10052307203612: [2,"176376","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:03:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:03:52 send SN10052307203612: [3,"176376",{}]
[ocpp  ] TRACE 2025/02/05 11:03:52 recv SN10052307203612: [2,"176377","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:52 send SN10052307203612: [3,"176377",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:03:53 recv SN10052307203612: [2,"176378","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:03:53 send SN10052307203612: [3,"176378",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:01 send SN10052307203612: [2,"1831160737","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:01 recv SN10052307203612: [2,"176379","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:01 send SN10052307203612: [3,"176379",{}]
[ocpp  ] TRACE 2025/02/05 11:04:02 recv SN10052307203612: [3,"1831160737",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:02 recv SN10052307203612: [2,"176380","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324538.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:02 send SN10052307203612: [3,"176380",{}]
[ocpp  ] TRACE 2025/02/05 11:04:02 recv SN10052307203612: [2,"176381","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:02 send SN10052307203612: [3,"176381",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:03 recv SN10052307203612: [2,"176382","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":418,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:03 send SN10052307203612: [3,"176382",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:11 send SN10052307203612: [2,"3040088516","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:12 recv SN10052307203612: [3,"3040088516",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:12 recv SN10052307203612: [2,"176383","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:12 send SN10052307203612: [3,"176383",{}]
[ocpp  ] TRACE 2025/02/05 11:04:12 recv SN10052307203612: [2,"176384","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:12 send SN10052307203612: [3,"176384",{}]
[ocpp  ] TRACE 2025/02/05 11:04:12 recv SN10052307203612: [2,"176385","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:12 send SN10052307203612: [3,"176385",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:13 recv SN10052307203612: [2,"176386","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:13 send SN10052307203612: [3,"176386",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:18 recv SN10052307203612: [2,"176387","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:04:18 send SN10052307203612: [3,"176387",{"currentTime":"2025-02-05T00:04:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:04:21 send SN10052307203612: [2,"278315512","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:21 recv SN10052307203612: [2,"176388","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1515.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:21 send SN10052307203612: [3,"176388",{}]
[ocpp  ] TRACE 2025/02/05 11:04:22 recv SN10052307203612: [3,"278315512",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:22 recv SN10052307203612: [2,"176389","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.0","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1515.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:22 send SN10052307203612: [3,"176389",{}]
[ocpp  ] TRACE 2025/02/05 11:04:23 recv SN10052307203612: [2,"176390","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":420,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:23 send SN10052307203612: [3,"176390",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:23 recv SN10052307203612: [2,"176391","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":420,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:23 send SN10052307203612: [3,"176391",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:31 send SN10052307203612: [2,"2373539025","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:32 recv SN10052307203612: [2,"176392","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:32 recv SN10052307203612: [3,"2373539025",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:32 send SN10052307203612: [3,"176392",{}]
[ocpp  ] TRACE 2025/02/05 11:04:32 recv SN10052307203612: [2,"176393","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324551.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:32 send SN10052307203612: [3,"176393",{}]
[ocpp  ] TRACE 2025/02/05 11:04:32 recv SN10052307203612: [2,"176394","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":420,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:32 send SN10052307203612: [3,"176394",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:33 recv SN10052307203612: [2,"176395","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":420,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:33 send SN10052307203612: [3,"176395",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:41 send SN10052307203612: [2,"2318207776","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:42 recv SN10052307203612: [2,"176396","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:42 send SN10052307203612: [3,"176396",{}]
[ocpp  ] TRACE 2025/02/05 11:04:42 recv SN10052307203612: [3,"2318207776",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:42 recv SN10052307203612: [2,"176397","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:42 send SN10052307203612: [3,"176397",{}]
[ocpp  ] TRACE 2025/02/05 11:04:43 recv SN10052307203612: [2,"176398","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":618,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:43 send SN10052307203612: [3,"176398",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:43 recv SN10052307203612: [2,"176399","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:43 send SN10052307203612: [3,"176399",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:51 send SN10052307203612: [2,"2624509276","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:04:52 recv SN10052307203612: [2,"176400","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1513.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:52 send SN10052307203612: [3,"176400",{}]
[ocpp  ] TRACE 2025/02/05 11:04:52 recv SN10052307203612: [3,"2624509276",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:52 recv SN10052307203612: [2,"176401","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:04:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1513.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:04:52 send SN10052307203612: [3,"176401",{}]
[ocpp  ] TRACE 2025/02/05 11:04:54 recv SN10052307203612: [2,"176402","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:54 send SN10052307203612: [3,"176402",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:04:54 recv SN10052307203612: [2,"176403","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:04:54 send SN10052307203612: [3,"176403",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:01 send SN10052307203612: [2,"3141036082","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:01 recv SN10052307203612: [3,"3141036082",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:02 recv SN10052307203612: [2,"176404","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1513.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:02 send SN10052307203612: [3,"176404",{}]
[ocpp  ] TRACE 2025/02/05 11:05:02 recv SN10052307203612: [2,"176405","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324564.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:02 send SN10052307203612: [3,"176405",{}]
[ocpp  ] TRACE 2025/02/05 11:05:03 recv SN10052307203612: [2,"176406","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:03 send SN10052307203612: [3,"176406",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:03 recv SN10052307203612: [2,"176407","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":421,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:03 send SN10052307203612: [3,"176407",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:11 send SN10052307203612: [2,"889621607","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:11 recv SN10052307203612: [2,"176408","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:11 send SN10052307203612: [3,"176408",{}]
[ocpp  ] TRACE 2025/02/05 11:05:12 recv SN10052307203612: [3,"889621607",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:12 recv SN10052307203612: [2,"176409","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:12 send SN10052307203612: [3,"176409",{}]
[ocpp  ] TRACE 2025/02/05 11:05:12 recv SN10052307203612: [2,"176410","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":422,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:12 send SN10052307203612: [3,"176410",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:13 recv SN10052307203612: [2,"176411","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":422,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:13 send SN10052307203612: [3,"176411",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:18 recv SN10052307203612: [2,"176412","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:05:18 send SN10052307203612: [3,"176412",{"currentTime":"2025-02-05T00:05:18Z"}]
[ocpp  ] TRACE 2025/02/05 11:05:21 send SN10052307203612: [2,"188136348","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:21 recv SN10052307203612: [2,"176413","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.5","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:21 send SN10052307203612: [3,"176413",{}]
[ocpp  ] TRACE 2025/02/05 11:05:22 recv SN10052307203612: [3,"188136348",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:22 recv SN10052307203612: [2,"176414","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.5","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:22 send SN10052307203612: [3,"176414",{}]
[ocpp  ] TRACE 2025/02/05 11:05:22 recv SN10052307203612: [2,"176415","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":422,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:22 send SN10052307203612: [3,"176415",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:23 recv SN10052307203612: [2,"176416","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":422,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:23 send SN10052307203612: [3,"176416",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:31 send SN10052307203612: [2,"2359857871","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:31 recv SN10052307203612: [3,"2359857871",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:32 recv SN10052307203612: [2,"176417","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.5","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1518.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:32 send SN10052307203612: [3,"176417",{}]
[ocpp  ] TRACE 2025/02/05 11:05:32 recv SN10052307203612: [2,"176418","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324577.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:32 send SN10052307203612: [3,"176418",{}]
[ocpp  ] TRACE 2025/02/05 11:05:33 recv SN10052307203612: [2,"176419","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:33 send SN10052307203612: [3,"176419",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:33 recv SN10052307203612: [2,"176420","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:33 send SN10052307203612: [3,"176420",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:41 send SN10052307203612: [2,"3748274261","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:42 recv SN10052307203612: [2,"176421","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:42 send SN10052307203612: [3,"176421",{}]
[ocpp  ] TRACE 2025/02/05 11:05:42 recv SN10052307203612: [3,"3748274261",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:42 recv SN10052307203612: [2,"176422","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:42 send SN10052307203612: [3,"176422",{}]
[ocpp  ] TRACE 2025/02/05 11:05:42 recv SN10052307203612: [2,"176423","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:42 send SN10052307203612: [3,"176423",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:43 recv SN10052307203612: [2,"176424","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:43 send SN10052307203612: [3,"176424",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:51 send SN10052307203612: [2,"1910049686","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:05:52 recv SN10052307203612: [3,"1910049686",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:52 recv SN10052307203612: [2,"176425","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:52 send SN10052307203612: [3,"176425",{}]
[ocpp  ] TRACE 2025/02/05 11:05:52 recv SN10052307203612: [2,"176426","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:05:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:05:52 send SN10052307203612: [3,"176426",{}]
[ocpp  ] TRACE 2025/02/05 11:05:53 recv SN10052307203612: [2,"176427","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:53 send SN10052307203612: [3,"176427",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:05:53 recv SN10052307203612: [2,"176428","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2047,\"leakcurr\":10,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:05:53 send SN10052307203612: [3,"176428",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:01 send SN10052307203612: [2,"990895189","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:02 recv SN10052307203612: [3,"990895189",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:02 recv SN10052307203612: [2,"176429","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:02 send SN10052307203612: [3,"176429",{}]
[ocpp  ] TRACE 2025/02/05 11:06:02 recv SN10052307203612: [2,"176430","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324590.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1517.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:02 send SN10052307203612: [3,"176430",{}]
[ocpp  ] TRACE 2025/02/05 11:06:03 recv SN10052307203612: [2,"176431","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":0,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:03 send SN10052307203612: [3,"176431",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:03 recv SN10052307203612: [2,"176432","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":0,\"AMBTemp\":423,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:03 send SN10052307203612: [3,"176432",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:11 send SN10052307203612: [2,"2000921693","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:12 recv SN10052307203612: [2,"176433","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:12 send SN10052307203612: [3,"176433",{}]
[ocpp  ] TRACE 2025/02/05 11:06:12 recv SN10052307203612: [3,"2000921693",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:12 recv SN10052307203612: [2,"176434","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:12 send SN10052307203612: [3,"176434",{}]
[ocpp  ] TRACE 2025/02/05 11:06:12 recv SN10052307203612: [2,"176435","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":0,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:12 send SN10052307203612: [3,"176435",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:13 recv SN10052307203612: [2,"176436","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":0,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:13 send SN10052307203612: [3,"176436",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:19 recv SN10052307203612: [2,"176437","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:06:19 send SN10052307203612: [3,"176437",{"currentTime":"2025-02-05T00:06:19Z"}]
[ocpp  ] TRACE 2025/02/05 11:06:21 send SN10052307203612: [2,"1166560306","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:22 recv SN10052307203612: [2,"176438","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:22 send SN10052307203612: [3,"176438",{}]
[ocpp  ] TRACE 2025/02/05 11:06:22 recv SN10052307203612: [3,"1166560306",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:22 recv SN10052307203612: [2,"176439","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:22 send SN10052307203612: [3,"176439",{}]
[ocpp  ] TRACE 2025/02/05 11:06:22 recv SN10052307203612: [2,"176440","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:22 send SN10052307203612: [3,"176440",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:23 recv SN10052307203612: [2,"176441","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:23 send SN10052307203612: [3,"176441",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:31 send SN10052307203612: [2,"1601839575","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:32 recv SN10052307203612: [3,"1601839575",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:32 recv SN10052307203612: [2,"176442","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:32 send SN10052307203612: [3,"176442",{}]
[ocpp  ] TRACE 2025/02/05 11:06:32 recv SN10052307203612: [2,"176443","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324603.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1510.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:32 send SN10052307203612: [3,"176443",{}]
[ocpp  ] TRACE 2025/02/05 11:06:33 recv SN10052307203612: [2,"176444","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:33 send SN10052307203612: [3,"176444",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:33 recv SN10052307203612: [2,"176445","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":425,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:33 send SN10052307203612: [3,"176445",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:41 send SN10052307203612: [2,"480876587","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:42 recv SN10052307203612: [2,"176446","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:42 send SN10052307203612: [3,"176446",{}]
[ocpp  ] TRACE 2025/02/05 11:06:42 recv SN10052307203612: [3,"480876587",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:42 recv SN10052307203612: [2,"176447","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:42 send SN10052307203612: [3,"176447",{}]
[ocpp  ] TRACE 2025/02/05 11:06:42 recv SN10052307203612: [2,"176448","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:42 send SN10052307203612: [3,"176448",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:43 recv SN10052307203612: [2,"176449","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:43 send SN10052307203612: [3,"176449",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:51 send SN10052307203612: [2,"2554643177","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:06:52 recv SN10052307203612: [3,"2554643177",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:52 recv SN10052307203612: [2,"176450","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:52 send SN10052307203612: [3,"176450",{}]
[ocpp  ] TRACE 2025/02/05 11:06:52 recv SN10052307203612: [2,"176451","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:06:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1516.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:06:52 send SN10052307203612: [3,"176451",{}]
[ocpp  ] TRACE 2025/02/05 11:06:53 recv SN10052307203612: [2,"176452","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:53 send SN10052307203612: [3,"176452",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:06:55 recv SN10052307203612: [2,"176453","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:06:55 send SN10052307203612: [3,"176453",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:01 send SN10052307203612: [2,"3866806818","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:02 recv SN10052307203612: [3,"3866806818",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:02 recv SN10052307203612: [2,"176454","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1516.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:02 send SN10052307203612: [3,"176454",{}]
[ocpp  ] TRACE 2025/02/05 11:07:02 recv SN10052307203612: [2,"176455","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:02 send SN10052307203612: [3,"176455",{}]
[ocpp  ] TRACE 2025/02/05 11:07:03 recv SN10052307203612: [2,"176456","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:03 send SN10052307203612: [3,"176456",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:03 recv SN10052307203612: [2,"176457","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":250,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":250,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:03 send SN10052307203612: [3,"176457",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:11 send SN10052307203612: [2,"2857106658","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:12 recv SN10052307203612: [3,"2857106658",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:12 recv SN10052307203612: [2,"176458","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.1","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324616.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1508.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:12 send SN10052307203612: [3,"176458",{}]
[ocpp  ] TRACE 2025/02/05 11:07:12 recv SN10052307203612: [2,"176459","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:11Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:12 send SN10052307203612: [3,"176459",{}]
[ocpp  ] TRACE 2025/02/05 11:07:13 recv SN10052307203612: [2,"176460","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:13 send SN10052307203612: [3,"176460",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:13 recv SN10052307203612: [2,"176461","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":426,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:13 send SN10052307203612: [3,"176461",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:19 recv SN10052307203612: [2,"176462","Heartbeat",{}]
[ocpp  ] TRACE 2025/02/05 11:07:19 send SN10052307203612: [3,"176462",{"currentTime":"2025-02-05T00:07:19Z"}]
[ocpp  ] TRACE 2025/02/05 11:07:21 send SN10052307203612: [2,"3511220463","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:22 recv SN10052307203612: [3,"3511220463",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:22 recv SN10052307203612: [2,"176463","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.3","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1509.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:22 send SN10052307203612: [3,"176463",{}]
[ocpp  ] TRACE 2025/02/05 11:07:22 recv SN10052307203612: [2,"176464","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:21Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.7","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1519.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:22 send SN10052307203612: [3,"176464",{}]
[ocpp  ] TRACE 2025/02/05 11:07:23 recv SN10052307203612: [2,"176465","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:23 send SN10052307203612: [3,"176465",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:23 recv SN10052307203612: [2,"176466","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:23 send SN10052307203612: [3,"176466",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:31 send SN10052307203612: [2,"3347659848","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:32 recv SN10052307203612: [3,"3347659848",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:32 recv SN10052307203612: [2,"176467","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.2"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"244.7","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1519.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:32 send SN10052307203612: [3,"176467",{}]
[ocpp  ] TRACE 2025/02/05 11:07:32 recv SN10052307203612: [2,"176468","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:31Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.9","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:32 send SN10052307203612: [3,"176468",{}]
[ocpp  ] TRACE 2025/02/05 11:07:33 recv SN10052307203612: [2,"176469","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:33 send SN10052307203612: [3,"176469",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:34 recv SN10052307203612: [2,"176470","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:34 send SN10052307203612: [3,"176470",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:41 send SN10052307203612: [2,"726276397","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:42 recv SN10052307203612: [3,"726276397",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:42 recv SN10052307203612: [2,"176471","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.9","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324629.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1507.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:42 send SN10052307203612: [3,"176471",{}]
[ocpp  ] TRACE 2025/02/05 11:07:42 recv SN10052307203612: [2,"176472","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:41Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324642.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:42 send SN10052307203612: [3,"176472",{}]
[ocpp  ] TRACE 2025/02/05 11:07:43 recv SN10052307203612: [2,"176473","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:43 send SN10052307203612: [3,"176473",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:43 recv SN10052307203612: [2,"176474","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:43 send SN10052307203612: [3,"176474",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:51 send SN10052307203612: [2,"3260198179","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2025/02/05 11:07:52 recv SN10052307203612: [3,"3260198179",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:52 recv SN10052307203612: [2,"176475","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.7","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324642.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Trigger"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:52 send SN10052307203612: [3,"176475",{}]
[ocpp  ] TRACE 2025/02/05 11:07:52 recv SN10052307203612: [2,"176476","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2025-02-05T00:07:51Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"6.1"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"243.8","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"324642.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"1506.0","context":"Sample.Periodic"}]}],"transactionId":1738268539}]
[ocpp  ] TRACE 2025/02/05 11:07:52 send SN10052307203612: [3,"176476",{}]
[ocpp  ] TRACE 2025/02/05 11:07:53 recv SN10052307203612: [2,"176477","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":618,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:53 send SN10052307203612: [3,"176477",{"status":"Accepted"}]
[ocpp  ] TRACE 2025/02/05 11:07:53 recv SN10052307203612: [2,"176478","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":100,\"cpVolt\":615,\"transactionId\":1738268539,\"totalPowerFactor\":246,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":246,\"offset0\":2006,\"offset1\":2048,\"leakcurr\":10,\"AMBTemp\":427,\"lockStatus\":1,\"ctCurrent\":15}"}]
[ocpp  ] TRACE 2025/02/05 11:07:53 send SN10052307203612: [3,"176478",{"status":"Accepted"}]

@andig
Copy link
Member

andig commented Feb 5, 2025

@jeffborg please omit cache and db from the logs- it's just noise. If there's a deadlock we need to know where. For that purpose please start with --profile and add the goroutine profile from /debug/pprof.

@andig andig added the question Rather clarification than issue label Feb 5, 2025
@andig
Copy link
Member

andig commented Feb 5, 2025

Also disconnects from mqtt broker

Also not visible in the log afaikt. Anyway the log is too noisy to see much.

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 5, 2025

@andig worked it out what --profile does, just using nightly as both nightly and 0.133.0 have the same issue

First time running and this happened - 2nd start was ok so will be another comment once it stops functioning I'll get the profile out.

[main  ] INFO 2025/02/06 08:46:45 evcc 0.133.0 (6fdda39e)
[main  ] INFO 2025/02/06 08:46:45 using config file: /config/evcc.yaml
[db    ] INFO 2025/02/06 08:46:45 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2025/02/06 08:46:47 connecting evcc-719386966 at tcp://mqtt-mosquitto:1883
[mqtt  ] DEBUG 2025/02/06 08:46:47 tcp://mqtt-mosquitto:1883 connected
[main  ] INFO 2025/02/06 08:46:47 listening at :7070
fatal error: concurrent map iteration and map write

goroutine 1 [running]:
github.com/evcc-io/evcc/util.CaptureLogs(...)
	github.com/evcc-io/evcc/util/log.go:160
github.com/evcc-io/evcc/cmd.runRoot(0x55cc6a0, {0x289aeb3?, 0x7?, 0x288e9d8?})
	github.com/evcc-io/evcc/cmd/root.go:177 +0x784
github.com/spf13/cobra.(*Command).execute(0x55cc6a0, {0x400011a0d0, 0x3, 0x3})
	github.com/spf13/[email protected]/command.go:989 +0x81c
github.com/spf13/cobra.(*Command).ExecuteC(0x55cc6a0)
	github.com/spf13/[email protected]/command.go:1117 +0x344
github.com/spf13/cobra.(*Command).Execute(...)
	github.com/spf13/[email protected]/command.go:1041
github.com/evcc-io/evcc/cmd.Execute()
	github.com/evcc-io/evcc/cmd/root.go:118 +0x24
main.main()
	github.com/evcc-io/evcc/main.go:44 +0x38

goroutine 67 [chan receive]:
github.com/evcc-io/evcc/util.(*Tee).Run(0x4000c948e0, 0x400010aaf0)
	github.com/evcc-io/evcc/util/tee.go:39 +0x44
created by github.com/evcc-io/evcc/cmd.runRoot in goroutine 1
	github.com/evcc-io/evcc/cmd/root.go:153 +0x33c

goroutine 9 [select]:
database/sql.(*DB).connectionOpener(0x400043b860, {0x3198cb8, 0x40004cea50})
	database/sql/sql.go:1253 +0x80
created by database/sql.OpenDB in goroutine 1
	database/sql/sql.go:833 +0x128

goroutine 10 [chan receive]:
github.com/evcc-io/evcc/cmd.configureDatabase.func2()
	github.com/evcc-io/evcc/cmd/setup.go:550 +0x64
created by github.com/evcc-io/evcc/cmd.configureDatabase in goroutine 1
	github.com/evcc-io/evcc/cmd/setup.go:549 +0x88

goroutine 36 [select]:
google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0x400190aa90, {0x3198cb8, 0x4000614d20})
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:88 +0xc4
created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 1
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:52 +0x118

goroutine 37 [select]:
google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0x400190aac0, {0x3198cb8, 0x4000614d70})
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:88 +0xc4
created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 1
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:52 +0x118

goroutine 38 [select]:
google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run(0x400190aaf0, {0x3198cb8, 0x4000614dc0})
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:88 +0xc4
created by google.golang.org/grpc/internal/grpcsync.NewCallbackSerializer in goroutine 1
	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:52 +0x118

goroutine 46 [IO wait]:
internal/poll.runtime_pollWait(0x7f577d7e20, 0x72)
	runtime/netpoll.go:351 +0xa0
internal/poll.(*pollDesc).wait(0x4000764a00?, 0x40008bc000?, 0x0)
	internal/poll/fd_poll_runtime.go:84 +0x28
internal/poll.(*pollDesc).waitRead(...)
	internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0x4000764a00, {0x40008bc000, 0xc00, 0xc00})
	internal/poll/fd_unix.go:165 +0x1fc
net.(*netFD).Read(0x4000764a00, {0x40008bc000?, 0x4001a79878?, 0x6ce8c0?})
	net/fd_posix.go:55 +0x28
net.(*conn).Read(0x40009ecf40, {0x40008bc000?, 0x4001a798b8?, 0xb8?})
	net/net.go:189 +0x34
crypto/tls.(*atLeastReader).Read(0x4001afa498, {0x40008bc000?, 0x0?, 0x4001afa498?})
	crypto/tls/conn.go:809 +0x40
bytes.(*Buffer).ReadFrom(0x4001a8b0b8, {0x317b020, 0x4001afa498})
	bytes/buffer.go:211 +0x90
crypto/tls.(*Conn).readFromUntil(0x4001a8ae08, {0x3175ba0, 0x40009ecf40}, 0x0?)
	crypto/tls/conn.go:831 +0xd0
crypto/tls.(*Conn).readRecordOrCCS(0x4001a8ae08, 0x0)
	crypto/tls/conn.go:629 +0x35c
crypto/tls.(*Conn).readRecord(...)
	crypto/tls/conn.go:591
crypto/tls.(*Conn).Read(0x4001a8ae08, {0x4001b32000, 0x8000, 0x0?})
	crypto/tls/conn.go:1385 +0x164
bufio.(*Reader).Read(0x4001b308a0, {0x40017aa2e0, 0x9, 0x0?})
	bufio/bufio.go:241 +0x1b4
io.ReadAtLeast({0x3175e40, 0x4001b308a0}, {0x40017aa2e0, 0x9, 0x9}, 0x9)
	io/io.go:335 +0xa0
io.ReadFull(...)
	io/io.go:354
golang.org/x/net/http2.readFrameHeader({0x40017aa2e0, 0x9, 0x4001b84030?}, {0x3175e40?, 0x4001b308a0?})
	golang.org/x/[email protected]/http2/frame.go:237 +0x58
golang.org/x/net/http2.(*Framer).ReadFrame(0x40017aa2a0)
	golang.org/x/[email protected]/http2/frame.go:501 +0x78
google.golang.org/grpc/internal/transport.(*http2Client).reader(0x4001857d48, 0x40000b9030)
	google.golang.org/[email protected]/internal/transport/http2_client.go:1639 +0x158
created by google.golang.org/grpc/internal/transport.NewHTTP2Client in goroutine 39
	google.golang.org/[email protected]/internal/transport/http2_client.go:413 +0x16c0

goroutine 16 [select]:
google.golang.org/grpc/internal/transport.(*controlBuffer).get(0x4001930400, 0x1)
	google.golang.org/[email protected]/internal/transport/controlbuf.go:412 +0x140
google.golang.org/grpc/internal/transport.(*loopyWriter).run(0x4000023800)
	google.golang.org/[email protected]/internal/transport/controlbuf.go:575 +0x74
google.golang.org/grpc/internal/transport.NewHTTP2Client.func6()
	google.golang.org/[email protected]/internal/transport/http2_client.go:471 +0xb8
created by google.golang.org/grpc/internal/transport.NewHTTP2Client in goroutine 39
	google.golang.org/[email protected]/internal/transport/http2_client.go:469 +0x1b54

goroutine 24 [select]:
github.com/eclipse/paho%2emqtt%2egolang.keepalive(0x4000139408, {0x7f574233c0, 0x4000a89848})
	github.com/eclipse/[email protected]/ping.go:48 +0x190
created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 20
	github.com/eclipse/[email protected]/client.go:595 +0x8ac

goroutine 25 [chan receive]:
github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch.func2()
	github.com/eclipse/[email protected]/router.go:174 +0x84
created by github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch in goroutine 20
	github.com/eclipse/[email protected]/router.go:173 +0x200

goroutine 27 [select]:
github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func1()
	github.com/eclipse/[email protected]/client.go:627 +0xf0
created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 20
	github.com/eclipse/[email protected]/client.go:624 +0x680

goroutine 28 [IO wait]:
internal/poll.runtime_pollWait(0x7f577d7d08, 0x72)
	runtime/netpoll.go:351 +0xa0
internal/poll.(*pollDesc).wait(0x4000764b00?, 0x4000ceb87e?, 0x0)
	internal/poll/fd_poll_runtime.go:84 +0x28
internal/poll.(*pollDesc).waitRead(...)
	internal/poll/fd_poll_runtime.go:89
internal/poll.(*FD).Read(0x4000764b00, {0x4000ceb87e, 0x1, 0x1})
	internal/poll/fd_unix.go:165 +0x1fc
net.(*netFD).Read(0x4000764b00, {0x4000ceb87e?, 0x2453e00?, 0x4001c16e00?})
	net/fd_posix.go:55 +0x28
net.(*conn).Read(0x4000a89848, {0x4000ceb87e?, 0x4001ac1798?, 0x55fbd40?})
	net/net.go:189 +0x34
io.ReadAtLeast({0x3175ba0, 0x4000a89848}, {0x4000ceb87e, 0x1, 0x1}, 0x1)
	io/io.go:335 +0xa0
io.ReadFull(...)
	io/io.go:354
github.com/eclipse/paho.mqtt.golang/packets.ReadPacket({0x3175ba0, 0x4000a89848})
	github.com/eclipse/[email protected]/packets/packets.go:131 +0x5c
github.com/eclipse/paho%2emqtt%2egolang.startIncoming.func1()
	github.com/eclipse/[email protected]/net.go:124 +0xdc
created by github.com/eclipse/paho%2emqtt%2egolang.startIncoming in goroutine 20
	github.com/eclipse/[email protected]/net.go:122 +0x140

goroutine 29 [select]:
github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms.func1()
	github.com/eclipse/[email protected]/net.go:175 +0x134
created by github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms in goroutine 20
	github.com/eclipse/[email protected]/net.go:164 +0x1a4

goroutine 30 [select]:
github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms.func1()
	github.com/eclipse/[email protected]/net.go:277 +0x164
created by github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms in goroutine 20
	github.com/eclipse/[email protected]/net.go:264 +0x204

goroutine 31 [chan receive]:
github.com/eclipse/paho%2emqtt%2egolang.startComms.func1()
	github.com/eclipse/[email protected]/net.go:407 +0x5c
created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 20
	github.com/eclipse/[email protected]/net.go:406 +0x1b4

goroutine 32 [chan receive]:
github.com/eclipse/paho%2emqtt%2egolang.startComms.func2()
	github.com/eclipse/[email protected]/net.go:430 +0x64
created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 20
	github.com/eclipse/[email protected]/net.go:429 +0x214

goroutine 65 [semacquire]:
sync.runtime_Semacquire(0x0?)
	runtime/sema.go:71 +0x2c
sync.(*WaitGroup).Wait(0x40009cb0b0)
	sync/waitgroup.go:118 +0x74
github.com/eclipse/paho%2emqtt%2egolang.startComms.func3()
	github.com/eclipse/[email protected]/net.go:438 +0x28
created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 20
	github.com/eclipse/[email protected]/net.go:437 +0x264

goroutine 66 [select]:
github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func2()
	github.com/eclipse/[email protected]/client.go:662 +0xa8
created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 20
	github.com/eclipse/[email protected]/client.go:657 +0x7bc

goroutine 68 [chan receive]:
github.com/evcc-io/evcc/util/pipe.(*Dropper).pipe(...)
	github.com/evcc-io/evcc/util/pipe/limiter.go:78
created by github.com/evcc-io/evcc/util/pipe.(*Dropper).Pipe in goroutine 1
	github.com/evcc-io/evcc/util/pipe/limiter.go:90 +0x94

goroutine 69 [chan receive]:
github.com/evcc-io/evcc/util.(*ParamCache).Run(0x4000c94900, 0x4001c18bd0)
	github.com/evcc-io/evcc/util/param.go:61 +0x5c
created by github.com/evcc-io/evcc/cmd.runRoot in goroutine 1
	github.com/evcc-io/evcc/cmd/root.go:157 +0x464

goroutine 70 [chan receive]:
github.com/evcc-io/evcc/util/pipe.(*Dropper).pipe(...)
	github.com/evcc-io/evcc/util/pipe/limiter.go:78
created by github.com/evcc-io/evcc/util/pipe.(*Dropper).Pipe in goroutine 1
	github.com/evcc-io/evcc/util/pipe/limiter.go:90 +0x94

goroutine 71 [select]:
github.com/evcc-io/evcc/server.(*SocketHub).Run(0x4001c4a690, 0x4001c18c40, 0x4000c94900)
	github.com/evcc-io/evcc/server/socket.go:147 +0x84
created by github.com/evcc-io/evcc/cmd.runRoot in goroutine 1
	github.com/evcc-io/evcc/cmd/root.go:174 +0x710

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 5, 2025

Also not visible in the log afaikt. Anyway the log is too noisy to see much.

Can tell because of this options.SetWill(topic, "offline", 1, true) even though no log in evcc the topic evcc/status changes to offline.

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 6, 2025

@andig here is your answer about which locks are causing an issue.

Seems in loadpoint_api.go SetDisableThreshold & GetMaxCurrent are deadlocked!

Yes I have home assistant setting the enable / disable threasholds externally. but this shouldn't cause any issues and clearly it is. This is not an issue in 0.132.1.

Also attached is the debug2 version just in case.
goroutine_debug_2.txt

debug=1 output

goroutine profile: total 46
3 @ 0x85de8 0x185b4 0x18184 0x2030038 0x8e604
#	0x2030037	github.com/evcc-io/evcc/util/pipe.(*Dropper).pipe+0x57	github.com/evcc-io/evcc/util/pipe/limiter.go:78

3 @ 0x85de8 0x6157c 0x6a7864 0x8e604
#	0x6a7863	google.golang.org/grpc/internal/grpcsync.(*CallbackSerializer).run+0xc3	google.golang.org/[email protected]/internal/grpcsync/callback_serializer.go:88

1 @ 0x1e0f4 0x882d0 0x5c486c 0x8e604
#	0x882cf		os/signal.signal_recv+0x2f	runtime/sigqueue.go:152
#	0x5c486b	os/signal.loop+0x1b		os/signal/signal_unix.go:23

1 @ 0x42d3c 0x84bf4 0x2006a44 0x2006860 0x2003df8 0x2018a04 0x2019318 0x31fd88 0x3219f4 0xf9a224 0x33e72c 0x31e93c 0x8e604
#	0x2006a43	runtime/pprof.writeRuntimeProfile+0xb3			runtime/pprof/pprof.go:793
#	0x200685f	runtime/pprof.writeGoroutine+0x4f			runtime/pprof/pprof.go:752
#	0x2003df7	runtime/pprof.(*Profile).WriteTo+0x147			runtime/pprof/pprof.go:374
#	0x2018a03	net/http/pprof.handler.ServeHTTP+0x443			net/http/pprof/pprof.go:272
#	0x2019317	net/http/pprof.Index+0xc7				net/http/pprof/pprof.go:388
#	0x31fd87	net/http.HandlerFunc.ServeHTTP+0x37			net/http/server.go:2220
#	0x3219f3	net/http.(*ServeMux).ServeHTTP+0x1b3			net/http/server.go:2747
#	0xf9a223	github.com/gorilla/mux.(*Router).ServeHTTP+0x193	github.com/gorilla/[email protected]/mux.go:212
#	0x33e72b	net/http.serverHandler.ServeHTTP+0xbb			net/http/server.go:3210
#	0x31e93b	net/http.(*conn).serve+0x4fb				net/http/server.go:2092

1 @ 0x85de8 0x176a0 0x172d8 0x1ee0638 0x8e604
#	0x1ee0637	github.com/evcc-io/evcc/core.(*Site).loopLoadpoints+0x67	github.com/evcc-io/evcc/core/site.go:1000

1 @ 0x85de8 0x176a0 0x172d8 0xd75800 0x8e604
#	0xd757ff	github.com/eclipse/paho%2emqtt%2egolang.startIncoming.func1+0xcf	github.com/eclipse/[email protected]/net.go:136

1 @ 0x85de8 0x176a0 0x172d8 0xd75f5c 0x8e604
#	0xd75f5b	github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms.func1+0x42b	github.com/eclipse/[email protected]/net.go:224

1 @ 0x85de8 0x176a0 0x172d8 0xd77eb4 0x8e604
#	0xd77eb3	github.com/eclipse/paho%2emqtt%2egolang.startComms.func1+0x93	github.com/eclipse/[email protected]/net.go:417

1 @ 0x85de8 0x185b4 0x18164 0x202f354 0x8e604
#	0x202f353	github.com/evcc-io/evcc/server/updater.(*watch).watchReleases+0x53	github.com/evcc-io/evcc/server/updater/watch.go:32

1 @ 0x85de8 0x185b4 0x18164 0x20ce4cc 0x8e604
#	0x20ce4cb	github.com/evcc-io/evcc/cmd.runRoot.func2+0x2b	github.com/evcc-io/evcc/cmd/root.go:271

1 @ 0x85de8 0x185b4 0x18164 0x20ce5f0 0x8e604
#	0x20ce5ef	github.com/evcc-io/evcc/cmd.runRoot.func1+0x8f	github.com/evcc-io/evcc/cmd/root.go:265

1 @ 0x85de8 0x185b4 0x18164 0xd6cf58 0x8e604
#	0xd6cf57	github.com/eclipse/paho%2emqtt%2egolang.(*client).internalConnLost.func1+0xb7	github.com/eclipse/[email protected]/client.go:544

1 @ 0x85de8 0x185b4 0x18164 0xfce564 0x8e604
#	0xfce563	github.com/evcc-io/evcc/charger/ocpp.(*Connector).WatchDog+0x43	github.com/evcc-io/evcc/charger/ocpp/connector.go:86

1 @ 0x85de8 0x185b4 0x18184 0x1e4e30c 0x8e604
#	0x1e4e30b	github.com/evcc-io/evcc/push.(*Hub).Run+0x5b	github.com/evcc-io/evcc/push/hub.go:100

1 @ 0x85de8 0x185b4 0x18184 0x1ee0278 0x8e604
#	0x1ee0277	github.com/evcc-io/evcc/core.(*Site).Prepare.func1+0x77	github.com/evcc-io/evcc/core/site.go:965

1 @ 0x85de8 0x185b4 0x18184 0x1f9648c 0x8e604
#	0x1f9648b	github.com/evcc-io/evcc/server.(*MQTT).Run+0x32b	github.com/evcc-io/evcc/server/mqtt.go:292

1 @ 0x85de8 0x185b4 0x18184 0x202f248 0x8e604
#	0x202f247	github.com/evcc-io/evcc/server/updater.Run+0x197	github.com/evcc-io/evcc/server/updater/run.go:22

1 @ 0x85de8 0x185b4 0x18184 0x20d2844 0x8e604
#	0x20d2843	github.com/evcc-io/evcc/cmd.configureDatabase.func2+0x63	github.com/evcc-io/evcc/cmd/setup.go:550

1 @ 0x85de8 0x185b4 0x18184 0x5056fc 0x8e604
#	0x5056fb	github.com/evcc-io/evcc/util.(*ParamCache).Run+0x5b	github.com/evcc-io/evcc/util/param.go:61

1 @ 0x85de8 0x185b4 0x18184 0x507104 0x8e604
#	0x507103	github.com/evcc-io/evcc/util.(*Tee).Run+0x43	github.com/evcc-io/evcc/util/tee.go:39

1 @ 0x85de8 0x185b4 0x18184 0xd6e330 0x8e604
#	0xd6e32f	github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func1+0x25f	github.com/eclipse/[email protected]/client.go:642

1 @ 0x85de8 0x185b4 0x18184 0xd77df4 0x8e604
#	0xd77df3	github.com/eclipse/paho%2emqtt%2egolang.startComms.func2+0x63	github.com/eclipse/[email protected]/net.go:430

1 @ 0x85de8 0x185b4 0x18184 0xfd850c 0x8e604
#	0xfd850b	github.com/evcc-io/evcc/charger/ocpp.(*CS).errorHandler+0x6b	github.com/evcc-io/evcc/charger/ocpp/cs.go:52

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x11171c 0x11170d 0x1f6418 0x2086a4 0x2a0058 0x2a01c0 0xaf9868 0xafb7f0 0xafc56c 0xafcb6c 0xfa4654 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f				runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27				internal/poll/fd_poll_runtime.go:84
#	0x11171b	internal/poll.(*pollDesc).waitRead+0x1fb			internal/poll/fd_poll_runtime.go:89
#	0x11170c	internal/poll.(*FD).Read+0x1ec					internal/poll/fd_unix.go:165
#	0x1f6417	net.(*netFD).Read+0x27						net/fd_posix.go:55
#	0x2086a3	net.(*conn).Read+0x33						net/net.go:189
#	0x2a0057	bufio.(*Reader).fill+0xf7					bufio/bufio.go:110
#	0x2a01bf	bufio.(*Reader).Peek+0x5f					bufio/bufio.go:148
#	0xaf9867	github.com/gorilla/websocket.(*Conn).read+0x27			github.com/gorilla/[email protected]/conn.go:371
#	0xafb7ef	github.com/gorilla/websocket.(*Conn).advanceFrame+0x5f		github.com/gorilla/[email protected]/conn.go:809
#	0xafc56b	github.com/gorilla/websocket.(*Conn).NextReader+0x8b		github.com/gorilla/[email protected]/conn.go:1009
#	0xafcb6b	github.com/gorilla/websocket.(*Conn).ReadMessage+0x1b		github.com/gorilla/[email protected]/conn.go:1093
#	0xfa4653	github.com/lorenzodonini/ocpp-go/ws.(*Server).readPump+0x143	github.com/lorenzodonini/[email protected]/ws/websocket.go:583

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x114ebc 0x114ead 0x1f7fe8 0x212698 0x21189c 0x3234a4 0x3231d4 0x20ce030 0x5b752c 0x5b7d04 0x20cc234 0x20cc229 0x20dfa58 0x4de58 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f			runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27			internal/poll/fd_poll_runtime.go:84
#	0x114ebb	internal/poll.(*pollDesc).waitRead+0x24b		internal/poll/fd_poll_runtime.go:89
#	0x114eac	internal/poll.(*FD).Accept+0x23c			internal/poll/fd_unix.go:620
#	0x1f7fe7	net.(*netFD).accept+0x27				net/fd_unix.go:172
#	0x212697	net.(*TCPListener).accept+0x27				net/tcpsock_posix.go:159
#	0x21189b	net.(*TCPListener).Accept+0x2b				net/tcpsock.go:372
#	0x3234a3	net/http.(*Server).Serve+0x293				net/http/server.go:3330
#	0x3231d3	net/http.(*Server).ListenAndServe+0x83			net/http/server.go:3259
#	0x20ce02f	github.com/evcc-io/evcc/cmd.runRoot+0x1d9f		github.com/evcc-io/evcc/cmd/root.go:334
#	0x5b752b	github.com/spf13/cobra.(*Command).execute+0x81b		github.com/spf13/[email protected]/command.go:989
#	0x5b7d03	github.com/spf13/cobra.(*Command).ExecuteC+0x343	github.com/spf13/[email protected]/command.go:1117
#	0x20cc233	github.com/spf13/cobra.(*Command).Execute+0x23		github.com/spf13/[email protected]/command.go:1041
#	0x20cc228	github.com/evcc-io/evcc/cmd.Execute+0x18		github.com/evcc-io/evcc/cmd/root.go:118
#	0x20dfa57	main.main+0x37						github.com/evcc-io/evcc/main.go:44
#	0x4de57		runtime.main+0x287					runtime/proc.go:272

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x114ebc 0x114ead 0x1f7fe8 0x212698 0x21189c 0x3234a4 0xfa2a5c 0xfacdd8 0xfcb484 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f					runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27					internal/poll/fd_poll_runtime.go:84
#	0x114ebb	internal/poll.(*pollDesc).waitRead+0x24b				internal/poll/fd_poll_runtime.go:89
#	0x114eac	internal/poll.(*FD).Accept+0x23c					internal/poll/fd_unix.go:620
#	0x1f7fe7	net.(*netFD).accept+0x27						net/fd_unix.go:172
#	0x212697	net.(*TCPListener).accept+0x27						net/tcpsock_posix.go:159
#	0x21189b	net.(*TCPListener).Accept+0x2b						net/tcpsock.go:372
#	0x3234a3	net/http.(*Server).Serve+0x293						net/http/server.go:3330
#	0xfa2a5b	github.com/lorenzodonini/ocpp-go/ws.(*Server).Start+0x44b		github.com/lorenzodonini/[email protected]/ws/websocket.go:410
#	0xfacdd7	github.com/lorenzodonini/ocpp-go/ocppj.(*Server).Start+0x177		github.com/lorenzodonini/[email protected]/ocppj/server.go:136
#	0xfcb483	github.com/lorenzodonini/ocpp-go/ocpp1%2e6.(*centralSystem).Start+0x23	github.com/lorenzodonini/[email protected]/ocpp1.6/central_system.go:541

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x114ebc 0x114ead 0x1f7fe8 0x2194b0 0x21809c 0x3234a4 0x1f982f8 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f				runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27				internal/poll/fd_poll_runtime.go:84
#	0x114ebb	internal/poll.(*pollDesc).waitRead+0x24b			internal/poll/fd_poll_runtime.go:89
#	0x114eac	internal/poll.(*FD).Accept+0x23c				internal/poll/fd_unix.go:620
#	0x1f7fe7	net.(*netFD).accept+0x27					net/fd_unix.go:172
#	0x2194af	net.(*UnixListener).accept+0x1f					net/unixsock_posix.go:172
#	0x21809b	net.(*UnixListener).Accept+0x2b					net/unixsock.go:260
#	0x3234a3	net/http.(*Server).Serve+0x293					net/http/server.go:3330
#	0x1f982f7	github.com/evcc-io/evcc/server.HealthListener.func1+0x27	github.com/evcc-io/evcc/server/uds.go:39

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x115b5c 0x115b49 0x20cd30 0x9341c8 0x93ed88 0x93ed79 0x20bc7e8 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f			runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27			internal/poll/fd_poll_runtime.go:84
#	0x115b5b	internal/poll.(*pollDesc).waitRead+0x12b		internal/poll/fd_poll_runtime.go:89
#	0x115b48	internal/poll.(*FD).RawRead+0x118			internal/poll/fd_unix.go:717
#	0x20cd2f	net.(*rawConn).Read+0x2f				net/rawconn.go:44
#	0x9341c7	golang.org/x/net/internal/socket.(*Conn).recvMsg+0x137	golang.org/x/[email protected]/internal/socket/rawconn_msg.go:27
#	0x93ed87	golang.org/x/net/internal/socket.(*Conn).RecvMsg+0x467	golang.org/x/[email protected]/internal/socket/socket.go:247
#	0x93ed78	golang.org/x/net/ipv4.(*payloadHandler).ReadFrom+0x458	golang.org/x/[email protected]/ipv4/payload_cmsg.go:31
#	0x20bc7e7	github.com/libp2p/zeroconf/v2.(*Server).recv4+0xb7	github.com/libp2p/zeroconf/[email protected]/server.go:275

1 @ 0x85de8 0x46a48 0x85090 0x1103f8 0x115b5c 0x115b49 0x20cd30 0x9341c8 0x9472d4 0x9472c9 0x20bc998 0x8e604
#	0x8508f		internal/poll.runtime_pollWait+0x9f			runtime/netpoll.go:351
#	0x1103f7	internal/poll.(*pollDesc).wait+0x27			internal/poll/fd_poll_runtime.go:84
#	0x115b5b	internal/poll.(*pollDesc).waitRead+0x12b		internal/poll/fd_poll_runtime.go:89
#	0x115b48	internal/poll.(*FD).RawRead+0x118			internal/poll/fd_unix.go:717
#	0x20cd2f	net.(*rawConn).Read+0x2f				net/rawconn.go:44
#	0x9341c7	golang.org/x/net/internal/socket.(*Conn).recvMsg+0x137	golang.org/x/[email protected]/internal/socket/rawconn_msg.go:27
#	0x9472d3	golang.org/x/net/internal/socket.(*Conn).RecvMsg+0x343	golang.org/x/[email protected]/internal/socket/socket.go:247
#	0x9472c8	golang.org/x/net/ipv6.(*payloadHandler).ReadFrom+0x338	golang.org/x/[email protected]/ipv6/payload_cmsg.go:31
#	0x20bc997	github.com/libp2p/zeroconf/v2.(*Server).recv6+0xb7	github.com/libp2p/zeroconf/[email protected]/server.go:300

1 @ 0x85de8 0x6157c 0x1ee0184 0x8e604
#	0x1ee0183	github.com/evcc-io/evcc/core.(*Site).Prepare.func2+0xb3	github.com/evcc-io/evcc/core/site.go:981

1 @ 0x85de8 0x6157c 0x1ee69ec 0x8e604
#	0x1ee69eb	github.com/smallnest/chanx.process[...]+0xdb	github.com/smallnest/[email protected]/unbounded_chan.go:68

1 @ 0x85de8 0x6157c 0x1f976b4 0x8e604
#	0x1f976b3	github.com/evcc-io/evcc/server.(*SocketHub).Run+0x83	github.com/evcc-io/evcc/server/socket.go:147

1 @ 0x85de8 0x6157c 0x524090 0x8e604
#	0x52408f	database/sql.(*DB).connectionOpener+0x7f	database/sql/sql.go:1253

1 @ 0x85de8 0x6157c 0xd6dfbc 0x8e604
#	0xd6dfbb	github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func2+0x2bb	github.com/eclipse/[email protected]/client.go:673

1 @ 0x85de8 0x6157c 0xd76da4 0x8e604
#	0xd76da3	github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms.func1+0x163	github.com/eclipse/[email protected]/net.go:277

1 @ 0x85de8 0x6157c 0xfa4e44 0x8e604
#	0xfa4e43	github.com/lorenzodonini/ocpp-go/ws.(*Server).writePump+0xc3	github.com/lorenzodonini/[email protected]/ws/websocket.go:610

1 @ 0x85de8 0x6157c 0xfa66e4 0x8e604
#	0xfa66e3	github.com/lorenzodonini/ocpp-go/ocppj.(*DefaultServerDispatcher).messagePump+0x133	github.com/lorenzodonini/[email protected]/ocppj/dispatcher.go:492

1 @ 0x85de8 0x62418 0x623f5 0x8771c 0x9d3c4 0xd6e8c4 0x8e604
#	0x8771b		sync.runtime_Semacquire+0x2b							runtime/sema.go:71
#	0x9d3c3		sync.(*WaitGroup).Wait+0x73							sync/waitgroup.go:118
#	0xd6e8c3	github.com/eclipse/paho%2emqtt%2egolang.(*client).stopCommsWorkers.func1+0xa3	github.com/eclipse/[email protected]/client.go:733

1 @ 0x85de8 0x62418 0x623f5 0x8771c 0x9d3c4 0xd77cf8 0x8e604
#	0x8771b		sync.runtime_Semacquire+0x2b					runtime/sema.go:71
#	0x9d3c3		sync.(*WaitGroup).Wait+0x73					sync/waitgroup.go:118
#	0xd77cf7	github.com/eclipse/paho%2emqtt%2egolang.startComms.func3+0x27	github.com/eclipse/[email protected]/net.go:438

1 @ 0x85de8 0x62418 0x623f5 0x87868 0x1ecfc8c 0x1ecfc39 0x1ed2324 0x1ed2700 0x1ed1f5c 0x1ed3698 0x1ed3a64 0x1ec9dd0 0x1edf560 0x1ee056c 0x20ce2cc 0x8e604
#	0x87867		sync.runtime_SemacquireRWMutexR+0x27					runtime/sema.go:100
#	0x1ecfc8b	sync.(*RWMutex).RLock+0x7b						sync/rwmutex.go:72
#	0x1ecfc38	github.com/evcc-io/evcc/core.(*Loadpoint).GetMaxCurrent+0x28		github.com/evcc-io/evcc/core/loadpoint_api.go:582
#	0x1ed2323	github.com/evcc-io/evcc/core.(*Loadpoint).effectiveMaxCurrent+0x23	github.com/evcc-io/evcc/core/loadpoint_effective.go:156
#	0x1ed26ff	github.com/evcc-io/evcc/core.(*Loadpoint).EffectiveMaxPower+0x1f	github.com/evcc-io/evcc/core/loadpoint_effective.go:205
#	0x1ed1f5b	github.com/evcc-io/evcc/core.(*Loadpoint).nextVehiclePlan+0x3fb		github.com/evcc-io/evcc/core/loadpoint_effective.go:85
#	0x1ed3697	github.com/evcc-io/evcc/core.(*Loadpoint).GetPlanGoal+0xb7		github.com/evcc-io/evcc/core/loadpoint_plan.go:69
#	0x1ed3a63	github.com/evcc-io/evcc/core.(*Loadpoint).plannerActive+0x1c3		github.com/evcc-io/evcc/core/loadpoint_plan.go:118
#	0x1ec9dcf	github.com/evcc-io/evcc/core.(*Loadpoint).Update+0x70f			github.com/evcc-io/evcc/core/loadpoint.go:1838
#	0x1edf55f	github.com/evcc-io/evcc/core.(*Site).update+0x52f			github.com/evcc-io/evcc/core/site.go:901
#	0x1ee056b	github.com/evcc-io/evcc/core.(*Site).Run+0x22b				github.com/evcc-io/evcc/core/site.go:1024
#	0x20ce2cb	github.com/evcc-io/evcc/cmd.runRoot.func4+0x2b				github.com/evcc-io/evcc/cmd/root.go:312

1 @ 0x85de8 0x62418 0x623f5 0x878c8 0x9ce18 0x1ece22c 0x1f955f4 0x1f955a4 0xd84adc 0xd85050 0xd7b1c4 0x8e604
#	0x878c7		sync.runtime_SemacquireRWMutex+0x27									runtime/sema.go:105
#	0x9ce17		sync.(*RWMutex).Lock+0xf7										sync/rwmutex.go:153
#	0x1ece22b	github.com/evcc-io/evcc/core.(*Loadpoint).SetDisableThreshold+0x3b					github.com/evcc-io/evcc/core/loadpoint_api.go:394
#	0x1f955f3	github.com/evcc-io/evcc/server.(*MQTT).listenLoadpointSetters.pass[...].func9+0x23			github.com/evcc-io/evcc/server/helper.go:17
#	0x1f955a3	github.com/evcc-io/evcc/server.(*MQTT).listenLoadpointSetters.floatSetter.setterFunc[...].func21+0x43	github.com/evcc-io/evcc/server/mqtt_setter.go:25
#	0xd84adb	github.com/evcc-io/evcc/plugin/mqtt.(*Client).ListenSetter.func1+0x4b					github.com/evcc-io/evcc/plugin/mqtt/client.go:193
#	0xd8504f	github.com/evcc-io/evcc/plugin/mqtt.(*Client).listen.func1+0x21f					github.com/evcc-io/evcc/plugin/mqtt/client.go:214
#	0xd7b1c3	github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch.func2+0x7f3				github.com/eclipse/[email protected]/router.go:218

1 @ 0x8e604


Logs just stop at 10:42:21 as you mentioned earlier not really much to see here except as above in original log the last message was a set for disableThreshold

[circuit-main] TRACE 2025/02/06 10:42:21 validate power: 947.56W + (0W -> 0W) <= 14900W ok
[mqtt  ] TRACE 2025/02/06 10:42:21 recv evcc/loadpoints/1/enableThreshold/set: '-384'
[lp-1  ] DEBUG 2025/02/06 10:42:21 set enable threshold: -384
[mqtt  ] TRACE 2025/02/06 10:42:21 send evcc/loadpoints/1/enableThreshold/set: ''
[site  ] DEBUG 2025/02/06 10:42:21 ----
[lp-1  ] DEBUG 2025/02/06 10:42:21 charge power: 0W
[lp-1  ] DEBUG 2025/02/06 10:42:21 charge currents: [0 0 0]A
[circuit-main] DEBUG 2025/02/06 10:42:21 power: 947.56W
[circuit-main] DEBUG 2025/02/06 10:42:21 current: 0A
[mqtt  ] TRACE 2025/02/06 10:42:21 recv evcc/loadpoints/1/enableThreshold/set: ''
[site  ] DEBUG 2025/02/06 10:42:21 grid power: 948W
[site  ] DEBUG 2025/02/06 10:42:21 pv 1 power: 1390W
[site  ] DEBUG 2025/02/06 10:42:21 site power: 823W
[lp-1  ] DEBUG 2025/02/06 10:42:21 charge voltages: [246 0 0]V
[lp-1  ] DEBUG 2025/02/06 10:42:21 detected connected phases: 1p
[lp-1  ] DEBUG 2025/02/06 10:42:21 charge total import: 332.822kWh
[mqtt  ] TRACE 2025/02/06 10:42:21 recv shellies/house-energy-monitor/emeter/0/power: '1037.15'
[lp-1  ] DEBUG 2025/02/06 10:42:21 charger status: B
[mqtt  ] TRACE 2025/02/06 10:42:21 recv shellies/house-energy-monitor/emeter/0/total: '12856409.6'
[mqtt  ] TRACE 2025/02/06 10:42:21 recv evcc/loadpoints/1/disableThreshold/set: '1318'

@jeffborg jeffborg changed the title possible mqtt deadlock deadlock in loadpoint api Feb 6, 2025
@andig
Copy link
Member

andig commented Feb 6, 2025

Looks like a deadlock here, just not sure why this would deadlock:

1 @ 0x85de8 0x62418 0x623f5 0x87868 0x1ecfc8c 0x1ecfc39 0x1ed2324 0x1ed2700 0x1ed1f5c 0x1ed3698 0x1ed3a64 0x1ec9dd0 0x1edf560 0x1ee056c 0x20ce2cc 0x8e604
#	0x87867		sync.runtime_SemacquireRWMutexR+0x27					runtime/sema.go:100
#	0x1ecfc8b	sync.(*RWMutex).RLock+0x7b						sync/rwmutex.go:72
#	0x1ecfc38	github.com/evcc-io/evcc/core.(*Loadpoint).GetMaxCurrent+0x28		github.com/evcc-io/evcc/core/loadpoint_api.go:582
#	0x1ed2323	github.com/evcc-io/evcc/core.(*Loadpoint).effectiveMaxCurrent+0x23	github.com/evcc-io/evcc/core/loadpoint_effective.go:156
#	0x1ed26ff	github.com/evcc-io/evcc/core.(*Loadpoint).EffectiveMaxPower+0x1f	github.com/evcc-io/evcc/core/loadpoint_effective.go:205
#	0x1ed1f5b	github.com/evcc-io/evcc/core.(*Loadpoint).nextVehiclePlan+0x3fb		github.com/evcc-io/evcc/core/loadpoint_effective.go:85
#	0x1ed3697	github.com/evcc-io/evcc/core.(*Loadpoint).GetPlanGoal+0xb7		github.com/evcc-io/evcc/core/loadpoint_plan.go:69
#	0x1ed3a63	github.com/evcc-io/evcc/core.(*Loadpoint).plannerActive+0x1c3		github.com/evcc-io/evcc/core/loadpoint_plan.go:118
#	0x1ec9dcf	github.com/evcc-io/evcc/core.(*Loadpoint).Update+0x70f			github.com/evcc-io/evcc/core/loadpoint.go:1838
#	0x1edf55f	github.com/evcc-io/evcc/core.(*Site).update+0x52f			github.com/evcc-io/evcc/core/site.go:901
#	0x1ee056b	github.com/evcc-io/evcc/core.(*Site).Run+0x22b				github.com/evcc-io/evcc/core/site.go:1024
#	0x20ce2cb	github.com/evcc-io/evcc/cmd.runRoot.func4+0x2b				github.com/evcc-io/evcc/cmd/root.go:312

vs

1 @ 0x85de8 0x62418 0x623f5 0x878c8 0x9ce18 0x1ece22c 0x1f955f4 0x1f955a4 0xd84adc 0xd85050 0xd7b1c4 0x8e604
#	0x878c7		sync.runtime_SemacquireRWMutex+0x27									runtime/sema.go:105
#	0x9ce17		sync.(*RWMutex).Lock+0xf7										sync/rwmutex.go:153
#	0x1ece22b	github.com/evcc-io/evcc/core.(*Loadpoint).SetDisableThreshold+0x3b					github.com/evcc-io/evcc/core/loadpoint_api.go:394
#	0x1f955f3	github.com/evcc-io/evcc/server.(*MQTT).listenLoadpointSetters.pass[...].func9+0x23			github.com/evcc-io/evcc/server/helper.go:17
#	0x1f955a3	github.com/evcc-io/evcc/server.(*MQTT).listenLoadpointSetters.floatSetter.setterFunc[...].func21+0x43	github.com/evcc-io/evcc/server/mqtt_setter.go:25
#	0xd84adb	github.com/evcc-io/evcc/plugin/mqtt.(*Client).ListenSetter.func1+0x4b					github.com/evcc-io/evcc/plugin/mqtt/client.go:193
#	0xd8504f	github.com/evcc-io/evcc/plugin/mqtt.(*Client).listen.func1+0x21f					github.com/evcc-io/evcc/plugin/mqtt/client.go:214
#	0xd7b1c3	github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch.func2+0x7f3	

Apart from this shouldn't be happening, you've selected "no external automation". Where would these MQTT requests be coming from?

/cc @GrimmiMeloni

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 6, 2025

@andig Just dynamically setting the enable and disable thresholds. It wasn't an issue in 0.132.1 or hasn't been an issue beforehand. This shouldn't occur full stop.

I hammered a 2nd copy on my computer against these 2 methods and couldn't get a result. But that was go from the container.

My gut feeling it's an issue in go itself and if I rebuild 0.132.1 with the latest go because the Dockerfile doesn't limit the point version I may see the issue in 0.132.1 as well.

@andig
Copy link
Member

andig commented Feb 6, 2025

So there is external automation? Just to get the picture straight. Could you try the nightly, too?

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 6, 2025

@andig that was the nightly for the profile same as 0.133.0. Never bothered with 0.133.0 for profile. Forgot to add yes externally automations.

@GrimmiMeloni
Copy link
Collaborator

@andig I went through the call chain. The thread in Loadpoint.Update() (routine 153) already holds the RLock when requesting RLock. As I learned from you long ago that Locks are non-reentrant in Go, I think we have our smoking gun.... 💣

goroutine 153 [sync.RWMutex.RLock, 18 minutes]:
sync.runtime_SemacquireRWMutexR(0x4000d7d3e8?, 0x4c?, 0x0?)
        runtime/sema.go:100 +0x28
sync.(*RWMutex).RLock(...)
        sync/rwmutex.go:72
github.com/evcc-io/evcc/core.(*Loadpoint).GetMaxCurrent(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_api.go:582 +0x7c
github.com/evcc-io/evcc/core.(*Loadpoint).effectiveMaxCurrent(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_effective.go:156 +0x24
github.com/evcc-io/evcc/core.(*Loadpoint).EffectiveMaxPower(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_effective.go:205 +0x20
github.com/evcc-io/evcc/core.(*Loadpoint).nextVehiclePlan(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_effective.go:85 +0x3fc
github.com/evcc-io/evcc/core.(*Loadpoint).GetPlanGoal(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_plan.go:69 +0xb8          <<<<<<<<<<<<<<<<<< holds RLock()

github.com/evcc-io/evcc/core.(*Loadpoint).plannerActive(0x4001678908)
        github.com/evcc-io/evcc/core/loadpoint_plan.go:118 +0x1c4
github.com/evcc-io/evcc/core.(*Loadpoint).Update(0x4001678908, 0x4089b47ae147ae14, 0x0, {0x4000e6e808, 0x30, 0x30}, 0x0, 0x0, 0x0, 0x4000a367a0, ...)
        github.com/evcc-io/evcc/core/loadpoint.go:1838 +0x710
...

@andig
Copy link
Member

andig commented Feb 6, 2025

Nope. That's what RLocks are for- they just allow ready and you can have any number of those. I still don't get it. The RLock will only stall if a Lock is already being held.

@GrimmiMeloni
Copy link
Collaborator

Not when you are concurrently trying to acquire a write lock (like in this case the MQTT routine).

If any goroutine calls RWMutex.Lock while the lock is already held by one or more readers, concurrent calls to RWMutex.RLock will block until the writer has acquired (and released) the lock, to ensure that the lock eventually becomes available to the writer. Note that this prohibits recursive read-locking.
(source: https://pkg.go.dev/sync#RWMutex)

@andig
Copy link
Member

andig commented Feb 6, 2025

Of course. But the writer doesn‘t have the lock either. So who has?

@GrimmiMeloni
Copy link
Collaborator

Still 153.
It has the Read Lock from the initial locking I pointed out earlier.
While it is doing its thing the MQTT thread requests a write lock and blocks on that call.
Then the Loadpoint routine (153) reaches the point where it wants to once more acquire the Read Lock. And that Blocks, because the MQTT has requested a write lock.

It is exactly the behavior described in the docs I quoted.

@andig
Copy link
Member

andig commented Feb 6, 2025

Whaaaaat. Youre right. Never noticed that.
Das ist WIRKLICH furchtbar. Im Prinzip muss jetzt jede API-Funktion ohne Lock gedoppelt werden und jede(!) interne Funktion muss darauf abgeklopft werden, ob sie irgendwo nicht doch noch ein RLock verwendet. loadpoint.Update selbst darf nur noch Funktionen aufrufen die public API darstellen um Locking sicherzustellen und nicht aus Versehen selbst zu locken.

@jeffborg
Copy link
Contributor Author

jeffborg commented Feb 6, 2025

@andig this line is the cause of it 285b22e#diff-64161edf08f807019f3ade5b7d32ce0f06b9a42bab3450090d8223f3ec6c2ee0R85

Just did a git bisect and given the previous explanations makes total sense.

FYI if you want to recreate

  • Use cpulimit to slow down evcc - make sure evcc is cpu choked (I used cpulimit -c 1 -f -l 1 -- ./evcc --profile - 1 cpu and 1%)
  • hammer disableThreshold/set mqtt messages in
  • have an active plan
  • I also used the rest of my mqtt setup and the real teslamate data, but used the dummy charger.

This will cause the deadlock within 2 minutes max every time on the above commit, the commit before no issues.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
question Rather clarification than issue
Projects
None yet
Development

No branches or pull requests

3 participants