U mnie to samo.
Aktualna wersja Esphome 2023.10.6 działa bez zarzutu.
Każda aktualizacja do wersji 2023.11.xx i zaktualizowanie esp.yaml powoduje brak odczytów wodomierzy.
Usunięcie sekcji sensor w kodzie yaml powoduje odbieranie telegramów z moich wodomierzy. Natomiast jak mam wpisane sensory niestety cisza.
Testy prowadziłem w Homeassistant Adoon Esphome.
Sprawdzałem również czy problem występuje i po zainstalowaniu esphome w docker-compose identyczne zachowanie.
testy dla wersji
2023.11.03
2023.11.04
2023.11.05
2023.11.06
Nie sprawdzałem wersji 2023.11.1 i 2023.11.2
Mój kod
esphome:
name: wodomierz
#friendly_name: wodomierz
esp32:
board: nodemcu-32s
framework:
type: arduino
time:
- platform: sntp
id: time_sntp
external_components:
- source: github://SzczepanLeon/esphome-components@main
refresh: 0d
components: [ wmbus ]
web_server:
port: 80
version: 2
logger:
level: DEBUG
api:
encryption:
key: "/usV//VFUokEn296076onuYnhQ0iWEnPJnGBunYwp04="
ota:
password: "e34d0c3980ac9492897724849c021acd"
wifi:
ssid: !secret wifi_ssid
password: !secret wifi_password
manual_ip:
static_ip: 10.0.0.45
gateway: 10.0.0.1
subnet: 255.255.255.0
dns1: 8.8.8.8
dns2: 8.8.4.4
ap:
ssid: "Wodomierz Fallback Hotspot"
password: "cXdl86ZHZzDw"
captive_portal:
wmbus:
mosi_pin: GPIO32
clk_pin: GPIO33
miso_pin: GPIO19
gdo2_pin: GPIO21
gdo0_pin: GPIO22
cs_pin: GPIO23
clients:
- name: "wmbusmeters"
ip_address: "10.0.0.1"
port: 7227
- platform: wmbus
meter_id: 0x00126858
type: apator162
key: "00000000000000000000000000000000"
add_prefix: false
lqi:
name: "LQI glowny"
rssi:
name: "RSSI glowny"
total_water_m3:
name: "ESP_wodomierz_glowny"
filters:
offset: -233.006
force_update: true
broker: !secret mqtt_ip
username: !secret mqtt_username
password: !secret mqtt_password
discovery_retain: true
Logi
INFO Waiting for result...
INFO OTA successful
INFO Successfully uploaded program.
INFO Starting log output from 10.0.0.45 using esphome API
INFO Successfully connected to 10.0.0.45 in 7.098s
INFO Successful handshake with 10.0.0.45 in 0.781s
[17:23:09][V][mqtt.idf:109]: Event dispatched from event loop event_id=5
[17:23:09][V][mqtt.idf:137]: MQTT_EVENT_PUBLISHED, msg_id=39310
[17:23:09][I][app:102]: ESPHome version 2023.11.6 compiled on Nov 29 2023, 17:22:40
[17:23:09][C][wifi:559]: WiFi:
[17:23:09][C][wifi:391]: Local MAC: C8:F0:9E:2E:36:D4
[17:23:09][C][wifi:396]: SSID: 'SmartDom'[redacted]
[17:23:09][C][wifi:397]: IP Address: 10.0.0.45
[17:23:09][C][wifi:399]: BSSID: 7C:10:C9:A8:3E:81[redacted]
[17:23:09][C][wifi:400]: Hostname: 'wodomierz'
[17:23:09][C][wifi:402]: Signal strength: -25 dB ▂▄▆█
[17:23:09][V][wifi:404]: Priority: 0.0
[17:23:09][C][wifi:406]: Channel: 2
[17:23:09][C][wifi:407]: Subnet: 255.255.255.0
[17:23:09][C][wifi:408]: Gateway: 10.0.0.1
[17:23:09][C][wifi:409]: DNS1: 8.8.8.8
[17:23:09][C][wifi:410]: DNS2: 8.8.4.4
[17:23:09][C][logger:416]: Logger:
[17:23:09][C][logger:417]: Level: VERY_VERBOSE
[17:23:09][C][logger:418]: Log Baud Rate: 115200
[17:23:09][C][logger:420]: Hardware UART: UART0
[17:23:09][C][captive_portal:088]: Captive Portal:
[17:23:09][C][web_server:168]: Web Server:
[17:23:09][C][web_server:169]: Address: 10.0.0.45:80
[17:23:09][C][sntp:053]: SNTP Time:
[17:23:09][C][sntp:054]: Server 1: '0.pool.ntp.org'
[17:23:09][C][sntp:055]: Server 2: '1.pool.ntp.org'
[17:23:09][C][sntp:056]: Server 3: '2.pool.ntp.org'
[17:23:09][C][sntp:057]: Timezone: 'CET-1CEST,M3.5.0,M10.5.0/3'
[17:23:09][C][mdns:115]: mDNS:
[17:23:09][C][mdns:116]: Hostname: wodomierz
[17:23:09][V][mdns:117]: Services:
[17:23:09][V][mdns:119]: - _esphomelib, _tcp, 6053
[17:23:09][V][mdns:121]: TXT: version = 2023.11.6
[17:23:09][V][mdns:121]: TXT: mac = c8f09e2e36d4
[17:23:09][V][mdns:121]: TXT: platform = ESP32
[17:23:09][V][mdns:121]: TXT: board = nodemcu-32s
[17:23:09][V][mdns:121]: TXT: network = wifi
[17:23:09][V][mdns:121]: TXT: api_encryption = Noise_NNpsk0_25519_ChaChaPoly_SHA256
[17:23:09][V][mdns:119]: - _http, _tcp, 80
[17:23:09][C][ota:097]: Over-The-Air Updates:
[17:23:09][C][ota:098]: Address: 10.0.0.45:3232
[17:23:09][C][ota:101]: Using Password.
[17:23:09][C][api:139]: API Server:
[17:23:09][C][api:140]: Address: 10.0.0.45:6053
[17:23:09][C][api:142]: Using noise encryption: YES
[17:23:09][C][mqtt:133]: MQTT:
[17:23:09][C][mqtt:135]: Server Address: 10.0.0.9:1883 (10.0.0.9)
[17:23:09][C][mqtt:136]: Username: 'haos'[redacted]
[17:23:09][C][mqtt:137]: Client ID: 'wodomierz-c8f09e2e36d4'[redacted]
[17:23:09][C][mqtt:139]: Discovery prefix: 'homeassistant'
[17:23:09][C][mqtt:140]: Discovery retain: YES
[17:23:09][C][mqtt:142]: Topic Prefix: 'wodomierz'
[17:23:09][C][mqtt:144]: Log Topic: 'wodomierz/debug'
[17:23:09][C][mqtt:147]: Availability: 'wodomierz/status'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'LQI glowny':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/lqi_glowny/state'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'RSSI glowny':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/rssi_glowny/state'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'ESP_wodomierz_glowny':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/esp_wodomierz_glowny/state'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'LQI ogrodowy':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/lqi_ogrodowy/state'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'RSSI ogrodowy':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/rssi_ogrodowy/state'
[17:23:09][C][mqtt.sensor:028]: MQTT Sensor 'ESP_wodomierz_ogrodowy':
[17:23:09][C][mqtt.sensor:032]: State Topic: 'wodomierz/sensor/esp_wodomierz_ogrodowy/state'
[17:23:09][C][wmbus:412]: wM-Bus v2.3.1:
[17:23:09][C][wmbus:414]: Clients:
[17:23:09][C][wmbus:421]: wmbusmeters: 10.10.0.1:7227 TCP [rtl-wmbus]
[17:23:09][C][wmbus:429]: CC1101 SPI bus:
[17:23:09][C][wmbus:430]: MOSI Pin: GPIO32
[17:23:09][C][wmbus:431]: MISO Pin: GPIO19
[17:23:09][C][wmbus:432]: CLK Pin: GPIO33
[17:23:09][C][wmbus:433]: CS Pin: GPIO23
[17:23:09][C][wmbus:434]: GDO0 Pin: GPIO22
[17:23:09][C][wmbus:435]: GDO2 Pin: GPIO21
[17:23:09][C][wmbus:442]: Available drivers: amiplus, apator08, apator162, apatoreitn, bmeters, c5isf, compact5, dme07, elf, evo868, fhkvdataiii, hydrocalm3, hydrus, iperl, itron, izar, mkradio3, mkradio4, qheat, qwater, sharky774, topaseskr, ultrimis, unismart, vario451
[17:23:09][C][wmbus:460]: Meter:
[17:23:09][C][wmbus:461]: ID: 397445 [0x00061085]
[17:23:09][C][wmbus:462]: Type: apator162
[17:23:09][C][wmbus:463]: Mode: T1
[17:23:09][C][wmbus:464]: Key: '00000000000000000000000000000000'
[17:23:09][C][wmbus:466]: Sensor 'LQI ogrodowy'
[17:23:09][C][wmbus:466]: Device Class: 'signal_strength'
[17:23:09][C][wmbus:466]: State Class: 'measurement'
[17:23:09][C][wmbus:466]: Unit of Measurement: ''
[17:23:09][C][wmbus:466]: Accuracy Decimals: 0
[17:23:09][C][wmbus:466]: Sensor 'RSSI ogrodowy'
[17:23:09][C][wmbus:466]: Device Class: 'signal_strength'
[17:23:09][C][wmbus:466]: State Class: 'measurement'
[17:23:09][C][wmbus:466]: Unit of Measurement: 'dBm'
[17:23:09][C][wmbus:466]: Accuracy Decimals: 0
[17:23:09][C][wmbus:466]: Sensor 'ESP_wodomierz_ogrodowy'
[17:23:09][C][wmbus:466]: Device Class: 'water'
[17:23:09][C][wmbus:466]: State Class: 'total_increasing'
[17:23:09][C][wmbus:466]: Unit of Measurement: 'm³'
[17:23:09][C][wmbus:466]: Accuracy Decimals: 3
[17:23:09][C][wmbus:466]: Icon: 'mdi:water'
[17:23:09][V][wmbus:466]: Force Update: YES
[17:23:09][C][wmbus:460]: Meter:
[17:23:09][C][wmbus:461]: ID: 1206360 [0x00126858]
[17:23:09][C][wmbus:462]: Type: apator162
[17:23:09][C][wmbus:463]: Mode: T1
[17:23:09][C][wmbus:464]: Key: '00000000000000000000000000000000'
[17:23:09][C][wmbus:466]: Sensor 'LQI glowny'
[17:23:09][C][wmbus:466]: Device Class: 'signal_strength'
[17:23:09][C][wmbus:466]: State Class: 'measurement'
[17:23:09][C][wmbus:466]: Unit of Measurement: ''
[17:23:10][C][wmbus:466]: Accuracy Decimals: 0
[17:23:10][C][wmbus:466]: Sensor 'RSSI glowny'
[17:23:10][C][wmbus:466]: Device Class: 'signal_strength'
[17:23:10][C][wmbus:466]: State Class: 'measurement'
[17:23:10][C][wmbus:466]: Unit of Measurement: 'dBm'
[17:23:10][C][wmbus:466]: Accuracy Decimals: 0
[17:23:10][C][wmbus:466]: Sensor 'ESP_wodomierz_glowny'
[17:23:10][C][wmbus:466]: Device Class: 'water'
[17:23:10][C][wmbus:466]: State Class: 'total_increasing'
[17:23:10][C][wmbus:466]: Unit of Measurement: 'm³'
[17:23:10][C][wmbus:466]: Accuracy Decimals: 3
[17:23:10][C][wmbus:466]: Icon: 'mdi:water'
[17:23:10][V][wmbus:466]: Force Update: YES
[17:23:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=6132 (now=16133)
[17:23:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=16132 (now=26132)
[17:23:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=26132 (now=36133)
[17:23:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=4294947013 (now=39717)
[17:23:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=36132 (now=46136)
[17:23:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=46132 (now=56132)
[17:24:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=56132 (now=66134)
[17:24:09][VV][api.connection:130]: Sending keepalive PING...
[17:24:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:24:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 92 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 104 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 104 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 124 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 104 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 108 bytes
[17:24:15][V][json:038]: Attempting to allocate 512 bytes for JSON serialization
[17:24:15][V][json:058]: Size after shrink 124 bytes
[17:24:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=66132 (now=76135)
[17:24:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=76132 (now=86134)
[17:24:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=86132 (now=96133)
[17:24:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=39717 (now=99717)
[17:24:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=96132 (now=106133)
[17:24:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=106132 (now=116133)
[17:25:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=116132 (now=126132)
[17:25:09][VV][api.connection:130]: Sending keepalive PING...
[17:25:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:25:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:25:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=126132 (now=136132)
[17:25:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=136132 (now=146133)
[17:25:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=146132 (now=156133)
[17:25:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=99717 (now=159717)
[17:25:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=156132 (now=166132)
[17:25:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=166132 (now=176132)
[17:26:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=176132 (now=186132)
[17:26:09][VV][api.connection:130]: Sending keepalive PING...
[17:26:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:26:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:26:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=186132 (now=196132)
[17:26:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=196132 (now=206134)
[17:26:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=206132 (now=216136)
[17:26:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=159717 (now=219718)
[17:26:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=216132 (now=226133)
[17:26:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=226132 (now=236134)
[17:27:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=236132 (now=246132)
[17:27:09][VV][api.connection:130]: Sending keepalive PING...
[17:27:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:27:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:27:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=246132 (now=256132)
[17:27:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=256132 (now=266135)
[17:27:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=266132 (now=276133)
[17:27:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=219717 (now=279718)
[17:27:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=276132 (now=286136)
[17:27:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=286132 (now=296132)
[17:28:00][I][ota:117]: Boot seems successful, resetting boot loop counter.
[17:28:00][VV][esp32.preferences:040]: s_pending_save: key: 233825507, len: 4
[17:28:00][D][esp32.preferences:114]: Saving 1 preferences to flash...
[17:28:00][VV][esp32.preferences:123]: Checking if NVS data 233825507 has changed
[17:28:00][V][esp32.preferences:126]: sync: key: 233825507, len: 4
[17:28:01][D][esp32.preferences:143]: Saving 1 preferences to flash: 0 cached, 1 written, 0 failed
[17:28:01][W][component:214]: Component ota took a long time for an operation (0.07 s).
[17:28:01][W][component:215]: Components should block for at most 20-30ms.
[17:28:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=296132 (now=306133)
[17:28:09][VV][api.connection:130]: Sending keepalive PING...
[17:28:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:28:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:28:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=306132 (now=316132)
[17:28:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=316132 (now=326132)
[17:28:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=326132 (now=336133)
[17:28:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=279717 (now=339717)
[17:28:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=336132 (now=346133)
[17:28:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=346132 (now=356134)
[17:29:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=356132 (now=366135)
[17:29:09][VV][api.connection:130]: Sending keepalive PING...
[17:29:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:29:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:29:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=366132 (now=376134)
[17:29:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=376132 (now=386132)
[17:29:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=386132 (now=396132)
[17:29:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=339717 (now=399719)
[17:29:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=396132 (now=406134)
[17:29:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=406132 (now=416132)
[17:30:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=416132 (now=426135)
[17:30:09][VV][api.connection:130]: Sending keepalive PING...
[17:30:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:30:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:30:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=426132 (now=436133)
[17:30:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=436132 (now=446133)
[17:30:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=446132 (now=456133)
[17:30:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=399717 (now=459718)
[17:30:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=456132 (now=466132)
[17:30:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=466132 (now=476133)
[17:31:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=476132 (now=486133)
[17:31:09][VV][api.connection:130]: Sending keepalive PING...
[17:31:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:31:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:31:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=486132 (now=496133)
[17:31:26][VV][scheduler:226]: Running interval 'update' with interval=900000 last_execution=4294573305 (now=506010)
[17:31:26][D][sntp:078]: Synchronized time: 2023-11-29 17:31:26
[17:31:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=496132 (now=506133)
[17:31:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=506132 (now=516132)
[17:31:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=459717 (now=519718)
[17:31:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=516132 (now=526133)
[17:31:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=526132 (now=536135)
[17:32:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=536132 (now=546134)
[17:32:09][VV][api.connection:130]: Sending keepalive PING...
[17:32:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:32:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:32:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=546132 (now=556132)
[17:32:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=556132 (now=566134)
[17:32:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=566132 (now=576132)
[17:32:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=519717 (now=579717)
[17:32:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=576132 (now=586132)
[17:32:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=586132 (now=596133)
[17:33:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=596132 (now=606133)
[17:33:09][VV][api.connection:130]: Sending keepalive PING...
[17:33:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:33:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:33:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=606132 (now=616132)
[17:33:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=616132 (now=626132)
[17:33:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=626132 (now=636134)
[17:33:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=579717 (now=639718)
[17:33:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=636132 (now=646132)
[17:33:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=646132 (now=656132)
[17:34:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=656132 (now=666133)
[17:34:09][VV][api.connection:130]: Sending keepalive PING...
[17:34:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:34:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:34:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=666132 (now=676132)
[17:34:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=676132 (now=686132)
[17:34:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=686132 (now=696132)
[17:34:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=639717 (now=699718)
[17:34:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=696132 (now=706133)
[17:34:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=706132 (now=716134)
[17:35:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=716132 (now=726133)
[17:35:09][VV][api.connection:130]: Sending keepalive PING...
[17:35:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:35:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:35:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=726132 (now=736132)
[17:35:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=736132 (now=746134)
[17:35:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=746132 (now=756133)
[17:35:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=699717 (now=759718)
[17:35:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=756132 (now=766133)
[17:35:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=766132 (now=776134)
[17:36:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=776132 (now=786132)
[17:36:09][VV][api.connection:130]: Sending keepalive PING...
[17:36:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:36:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:36:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=786132 (now=796133)
[17:36:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=796132 (now=806133)
[17:36:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=806132 (now=816133)
[17:36:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=759717 (now=819717)
[17:36:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=816132 (now=826134)
[17:36:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=826132 (now=836133)
[17:37:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=836132 (now=846132)
[17:37:09][VV][api.connection:130]: Sending keepalive PING...
[17:37:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:37:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:37:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=846132 (now=856135)
[17:37:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=856132 (now=866132)
[17:37:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=866132 (now=876133)
[17:37:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=819717 (now=879718)
[17:37:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=876132 (now=886132)
[17:37:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=886132 (now=896132)
[17:38:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=896132 (now=906133)
[17:38:09][VV][api.connection:130]: Sending keepalive PING...
[17:38:09][VV][api.service:037]: send_ping_request: PingRequest {}
[17:38:09][VV][api.service:567]: on_ping_response: PingResponse {}
[17:38:16][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=906132 (now=916133)
[17:38:26][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=916132 (now=926133)
[17:38:36][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=926132 (now=936132)
[17:38:40][VV][scheduler:226]: Running interval '' with interval=60000 last_execution=879717 (now=939717)
[17:38:46][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=936132 (now=946134)
[17:38:56][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=946132 (now=956135)
[17:39:06][VV][scheduler:226]: Running interval '' with interval=10000 last_execution=956132 (now=966132)