From 5bd14e1d5b2ba01888bba531495b1ada1a85a427 Mon Sep 17 00:00:00 2001 From: Krzysztof Kallas Date: Tue, 29 Sep 2026 18:38:02 +0200 Subject: [PATCH] Test: live v13 box answers USB commands Capture from box 14:C1:9F:57:72:48 after flashing merged-binary-v13.bin: ping and wifi get replies, logs intact through the USB-Serial-JTAG driver. Co-Authored-By: Claude Opus 5.5 --- ...live_boot_14C19F577248_v13_usb_console.txt | 973 ++++++++++++++++++ tests/test_commands.py | 15 + 2 files changed, 988 insertions(+) create mode 100644 tests/fixtures/live_boot_14C19F577248_v13_usb_console.txt diff --git a/tests/fixtures/live_boot_14C19F577248_v13_usb_console.txt b/tests/fixtures/live_boot_14C19F577248_v13_usb_console.txt new file mode 100644 index 0000000..bdeb74c --- /dev/null +++ b/tests/fixtures/live_boot_14C19F577248_v13_usb_console.txt @@ -0,0 +1,973 @@ +--- connected to COM14 --- +W (9426) wifi:Haven't to connect to a suitable AP now! +--- reset issued by tool --- +ESP-ROM:esp32s3-20210327 +Build:Mar 27 2021 +rst:0x15 (USB_UART_CHIP_RESET),boot:0xb (SPI_FAST_FLASH_BOOT) +Saved PC:0x4037f366 +SPIWP:0xee +mode:DIO, clock div:1 +load:0x3fce2820,len:0x14f0 +load:0x403c8700,len:0xd24 +load:0x403cb700,len:0x2f48 +entry 0x403c8924 +I (24) boot: ESP-IDF v5.5.3-dirty 2nd stage bootloader +I (24) boot: compile time Sep 10 2026 13:24:39 +I (25) boot: Multicore bootloader +I (25) boot: chip revision: v0.2 +I (28) boot: efuse block revision: v1.4 +I (32) boot.esp32s3: Boot SPI Speed : 80MHz +I (35) boot.esp32s3: SPI Mode : DIO +I (39) boot.esp32s3: SPI Flash Size : 8MB +I (43) boot: Enabling RNG early entropy source... +I (48) boot: Partition Table: +I (50) boot: ## Label Usage Type ST Offset Length +I (56) boot: 0 esp_secure_cert unknown 3f 06 0000d000 00002000 +I (63) boot: 1 nvs WiFi data 01 02 00010000 00020000 +I (69) boot: 2 nvs_keys NVS keys 01 04 00030000 00001000 +I (76) boot: 3 otadata OTA data 01 00 00031000 00002000 +I (82) boot: 4 phy_init RF data 01 01 00033000 00001000 +I (89) boot: 5 ota_0 OTA app 00 10 00040000 00300000 +I (95) boot: 6 ota_1 OTA app 00 11 00340000 00300000 +I (102) boot: 7 fctry WiFi data 01 02 00640000 00006000 +I (109) boot: End of partition table +I (112) esp_image: segment 0: paddr=00040020 vaddr=3c190020 size=67420h (422944) map +I (194) esp_image: segment 1: paddr=000a7448 vaddr=3fc9c700 size=05d00h ( 23808) load +I (200) esp_image: segment 2: paddr=000ad150 vaddr=40374000 size=02ec8h ( 11976) load +I (203) esp_image: segment 3: paddr=000b0020 vaddr=42000020 size=187370h (1602416) map +I (488) esp_image: segment 4: paddr=00237398 vaddr=40376ec8 size=1580ch ( 88076) load +I (507) esp_image: segment 5: paddr=0024cbac vaddr=5000002c size=00020h ( 32) load +I (517) boot: Loaded app from partition at offset 0x40000 +I (518) boot: Disabling RNG early entropy source... +I (528) octal_psram: vendor id : 0x0d (AP) +I (528) octal_psram: dev id : 0x02 (generation 3) +I (529) octal_psram: density : 0x03 (64 Mbit) +I (530) octal_psram: good-die : 0x01 (Pass) +I (535) octal_psram: Latency : 0x01 (Fixed) +I (539) octal_psram: VCC : 0x01 (3V) +I (543) octal_psram: SRF : 0x01 (Fast Refresh) +I (548) octal_psram: BurstType : 0x01 (Hybrid Wrap) +I (553) octal_psram: BurstLen : 0x01 (32 Byte) +I (557) octal_psram: Readlatency : 0x02 (10 cycles@Fixed) +I (562) octal_psram: DriveStrength: 0x00 (1/1) +I (567) esp_psram: Found 8MB PSRAM device +I (570) esp_psram: Speed: 40MHz +I (573) cpu_start: Multicore app +I (1307) esp_psram: SPI SRAM memory test OK +I (1315) cpu_start: GPIO 44 and 43 are used as console UART I/O pins +I (1316) cpu_start: Pro cpu start user code +I (1316) cpu_start: cpu freq: 160000000 Hz +I (1318) app_init: Application information: +I (1322) app_init: Project name: kota-sensorboard +I (1327) app_init: App version: 1.0.16 +I (1331) app_init: Compile time: Sep 28 2026 15:07:57 +I (1336) app_init: ELF file SHA256: 3ae273ec1... +I (1340) app_init: ESP-IDF: v5.5.3-dirty +I (1345) efuse_init: Min chip rev: v0.0 +I (1348) efuse_init: Max chip rev: v0.99 +I (1353) efuse_init: Chip rev: v0.2 +I (1357) heap_init: Initializing. RAM available for dynamic allocation: +I (1363) heap_init: At 3FCB4AD0 len 00034C40 (211 KiB): RAM +I (1368) heap_init: At 3FCE9710 len 00005724 (21 KiB): RAM +I (1373) heap_init: At 3FCF0000 len 00008000 (32 KiB): DRAM +I (1379) heap_init: At 600FE000 len 00001FE8 (7 KiB): RTCRAM +I (1384) esp_psram: Adding pool of 8192K of PSRAM memory to heap allocator +I (1392) spi_flash: detected chip: generic +I (1394) spi_flash: flash io: dio +I (1400) sleep_gpio: Configure to isolate all GPIO pins in sleep state +I (1404) sleep_gpio: Enable automatic switching of GPIO sleep configuration +I (1419) main_task: Started on CPU0 +I (1422) esp_psram: Reserving pool of 32K of internal memory for DMA/internal allocations +I (1430) main_task: Calling app_main() +E (1433) ID: [E-MAIN-04] Device MAC address: 14:C1:9F:57:72:48 +I (1475) KOTA: led color=green pattern=solid +I (1676) I2C: Creating I2C mutex +I (1694) KOTA: i2c SDA=1 SCL=1 0x39=AS7341 0x68=DS1307 0x6B=SEN66 0x76=BME688 +I (1696) OTA_CLIENT: OTA token loaded from NVS: +I (1698) OTA_CLIENT: Device is not in selfcheck mode +I (1702) OTA_CLIENT: Update metadata loaded from NVS: version=15, status=2 +I (1707) OTA_CLIENT: Device is in factory state +I (1711) SPI: Creating SPI mutex +I (1755) SD_CARD: SD card mounted successfully +I (1756) SD_CARD: /sdcard exists, SD card initialized successfully +I (1758) INFLUX_BUFFER: Buffer file does not exist, returning count of 0 +I (1763) KOTA: sd type=SDHC size=29.2GB fs=FAT32 free=29.1GB env_keys=INFLUX_TOKEN,INFLUX_URL buffer_lines=0 +I (1774) WIFI_CREDENTIALS: SSID/PASSWORD not set in .env; Matter will be used for WiFi provisioning +I (1785) MAIN: Matter node created successfully +W (1787) data_model: Server Cluster 0x00000003 on endpoint 0x0001 already exists. Not creating again. +W (1793) data_model: Attribute 0x0000FFFC on cluster 0x00000003 already exists. Not creating again. +W (1802) data_model: Attribute 0x0000FFFD on cluster 0x00000003 already exists. Not creating again. +W (1811) data_model: Attribute 0x00000000 on cluster 0x00000003 already exists. Not creating again. +W (1819) data_model: Attribute 0x00000001 on cluster 0x00000003 already exists. Not creating again. +W (1828) data_model: Command 0x00000000 on cluster 0x00000003 already exists. Not creating again. +W (1837) data_model: Server Cluster 0x00000003 on endpoint 0x0001 already exists. Not creating again. +W (1846) data_model: Attribute 0x0000FFFC on cluster 0x00000003 already exists. Not creating again. +W (1854) data_model: Attribute 0x0000FFFD on cluster 0x00000003 already exists. Not creating again. +W (1863) data_model: Attribute 0x00000000 on cluster 0x00000003 already exists. Not creating again. +W (1872) data_model: Attribute 0x00000001 on cluster 0x00000003 already exists. Not creating again. +W (1881) data_model: Command 0x00000000 on cluster 0x00000003 already exists. Not creating again. +W (1890) data_model: Server Cluster 0x00000003 on endpoint 0x0001 already exists. Not creating again. +W (1898) data_model: Attribute 0x0000FFFC on cluster 0x00000003 already exists. Not creating again. +W (1907) data_model: Attribute 0x0000FFFD on cluster 0x00000003 already exists. Not creating again. +W (1916) data_model: Attribute 0x00000000 on cluster 0x00000003 already exists. Not creating again. +W (1925) data_model: Attribute 0x00000001 on cluster 0x00000003 already exists. Not creating again. +W (1933) data_model: Command 0x00000000 on cluster 0x00000003 already exists. Not creating again. +I (1944) pp: pp rom version: e7ae62f +I (1945) net80211: net80211 rom version: e7ae62f +I (1951) wifi:wifi driver task: 3fcc9610, prio:23, stack:6656, core=0 +I (1969) wifi:wifi firmware version: 4df78f2 +I (1970) wifi:wifi certification version: v7.0 +I (1971) wifi:config NVS flash: enabled +I (1971) wifi:config nano formatting: disabled +I (1975) wifi:Init data frame dynamic rx buffer num: 32 +I (1980) wifi:Init static rx mgmt buffer num: 5 +I (1984) wifi:Init management short buffer num: 32 +I (1988) wifi:Init dynamic tx buffer num: 32 +I (1992) wifi:Init static tx FG buffer num: 2 +I (1997) wifi:Init static rx buffer size: 1600 +I (2001) wifi:Init static rx buffer num: 10 +I (2004) wifi:Init dynamic rx buffer num: 32 +I (2009) wifi_init: rx ba win: 6 +I (2011) wifi_init: accept mbox: 6 +I (2014) wifi_init: tcpip mbox: 32 +I (2018) wifi_init: udp mbox: 6 +I (2020) wifi_init: tcp mbox: 6 +I (2023) wifi_init: tcp tx win: 5760 +I (2027) wifi_init: tcp rx win: 5760 +I (2030) wifi_init: tcp mss: 1440 +I (2045) chip[DL]: NVS set: chip-counters/reboot-count = 13 (0xD) +I (2048) chip[DL]: Real time clock set to 946684800 (2000-01-01 00:00:00 UTC) +I (2050) BLE_INIT: BT controller compile version [1bb2f50] +I (2053) BLE_INIT: Using main XTAL as clock source +I (2058) BLE_INIT: Feature Config, ADV:1, BLE_50:1, DTM:1, SCAN:1, CCA:0, SMP:1, CONNECT:1 +I (2067) BLE_INIT: Bluetooth MAC: 14:c1:9f:57:72:4a +I (2072) phy_init: phy_version 711,97bcf0a2,Aug 25 2025,19:04:10 +I (2119) NimBLE: GAP procedure initiated: stop advertising. + +I (2122) CHIP[DL]: BLE host-controller synced +I (2623) chip[DL]: Starting ESP WiFi layer +I (2626) wifi:mode : sta (14:c1:9f:57:72:48) +I (2627) wifi:enable tsf +W (2628) wifi:Haven't to connect to a suitable AP now! +I (2629) chip[DL]: +I (2629) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 43Attempting to connect WiFi station interface + +I (2641) chip[DL]: WiFi station state change: NotConnected -> Connecting +I (2642) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 2 +I (2647) chip[DL]: +Done driving station state, nothing else to do... +I (2659) chip[SVR]: Initializing subscription resumption storage... +I (2666) chip[SVR]: Server initializing... +I (2669) chip[TS]: Last Known Good Time: 2026-09-29T14:59:23 +I (2674) chip[DMG]: AccessControl: initializing +I (2678) chip[DMG]: Examples::AccessControlDelegate::Init +I (2683) chip[DMG]: AccessControl: setting +I (2687) chip[DMG]: DefaultAclStorage: initializing +I (2691) chip[DMG]: DefaultAclStorage: 0 entries loaded +I (2696) chip[SVR]: WARNING: mTestEventTriggerDelegate is null +I (2715) chip[ZCL]: Using ZAP configuration... +I (2716) esp_matter_cluster: Cluster plugin init common callback +I (2718) chip[DMG]: AccessControlCluster: initializing +I (2720) chip[ZCL]: WRITE ERR: ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported +I (2730) chip[DIS]: Updating services using commissioning mode 1 +I (2733) chip[DIS]: CHIP minimal mDNS started advertising. +I (2739) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 cp=0 +I (2749) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: 995B80B34BC8AAD9. +I (2758) chip[DIS]: mDNS service published: _matterc._udp +I (2763) chip[IN]: CASE Server enabling CASE session setups +I (2769) chip[SVR]: Joining Multicast groups +I (2772) chip[SVR]: Server Listening... +I (2778) esp_matter_attribute: ********** R : Endpoint 0x0000's Cluster 0x0000003C's Attribute 0x0000FFFC is 0 ********** +I (2788) data_model: Dynamic endpoint 0 added +E (2790) data_model: Cluster cannot be NULL. +I (2795) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000003's Attribute 0x00000001 is 2 ********** +I (2806) data_model: Dynamic endpoint 1 added +E (2809) data_model: Cluster cannot be NULL. +I (2815) chip[DL]: +I (2815) MAIN: ESP-Matter started successfully +WIFI_EVENT_STA_START +W (2821) wifi:Haven't to connect to a suitable AP now! +I (2826) chip[DL]: Attempting to connect WiFi station interface +E (2832) wifi:sta is connecting, return error +E (2836) chip[DL]: esp_wifi_connect() failed: ESP_ERR_WIFI_CONN +I (2842) KOTA: boot fw=1.0.16 build=3ae273ec hw=2 mac=14C19F577248 reset=USB_UART_CHIP_RESET mode=matter commissioned=0 last_restart=none +I (2843) chip[DL]: Configuring CHIPoBLE advertising (interval 25 ms, connectable) +I (2861) NimBLE: GAP procedure initiated: advertise; +I (2865) NimBLE: disc_mode=2 +I (2867) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=40 adv_itvl_max=40 +I (2877) NimBLE: + +I (2880) chip[DL]: CHIPoBLE advertising started +I (2883) MAIN: Commissioning window opened +I (2888) KOTA: matter fabrics=0 window=open qr=MT:Y.K9042C00KA0648G00 manual=34970112332 +I (2896) KOTA: led color=cyan pattern=breathe period=3000ms +I (2901) KOTA: matter fabrics=0 window=open qr=MT:Y.K9042C00KA0648G00 manual=34970112332 +W (2909) wifi:Haven't to connect to a suitable AP now! +I (2908) KOTA: health up=1s status=OK wifi=disconnected rssi=0 time_valid=0 sd=ok buffer=0 heap=117491 psram=7.9M last_upload=never ota=registered +W (3401) wifi:Haven't to connect to a suitable AP now! +I (3910) KOTA: rtc running=0 time=2000-01-01T00:00:00 +W (4402) wifi:Haven't to connect to a suitable AP now! +W (5403) wifi:Haven't to connect to a suitable AP now! +W (6404) wifi:Haven't to connect to a suitable AP now! +I (7219) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (7222) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (7223) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (7226) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (7233) wifi:Haven't to connect to a suitable AP now! +E (7238) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (7248) wifi:Haven't to connect to a suitable AP now! +I (7252) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (7259) wifi:Haven't to connect to a suitable AP now! +E (7264) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (7273) chip[DL]: Next WiFi station reconnect in 100 ms +I (7278) chip[DL]: Done driving station state, nothing else to do... +W (7379) wifi:Haven't to connect to a suitable AP now! +I (7380) chip[DL]: Attempting to connect WiFi station interface +I (7383) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (7386) wifi:Haven't to connect to a suitable AP now! +E (7391) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (7400) chip[DL]: Done driving station state, nothing else to do... +W (7405) wifi:Haven't to connect to a suitable AP now! +W (8411) wifi:Haven't to connect to a suitable AP now! +W (9415) wifi:Haven't to connect to a suitable AP now! +W (10417) wifi:Haven't to connect to a suitable AP now! +W (11419) wifi:Haven't to connect to a suitable AP now! +I (12201) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (12204) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (12205) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (12208) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (12215) wifi:Haven't to connect to a suitable AP now! +E (12220) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (12230) wifi:Haven't to connect to a suitable AP now! +I (12234) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (12242) wifi:Haven't to connect to a suitable AP now! +E (12247) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (12256) chip[DL]: Next WiFi station reconnect in 100 ms +I (12261) chip[DL]: Done driving station state, nothing else to do... +W (12361) wifi:Haven't to connect to a suitable AP now! +I (12362) chip[DL]: Attempting to connect WiFi station interface +I (12366) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (12368) wifi:Haven't to connect to a suitable AP now! +E (12373) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (12383) chip[DL]: Done driving station state, nothing else to do... +W (12427) wifi:Haven't to connect to a suitable AP now! +W (13428) wifi:Haven't to connect to a suitable AP now! +I (13912) INFLUX_BUFFER: Read INFLUX_URL from .env file: https://influx.kota-app.com/api/v2/write?bucket=sensorbox&org=kota +I (13917) INFLUX_BUFFER: Buffer file does not exist, returning count of 0 +W (13920) INFLUX: Initializing Influx client with URL: https://influx.kota-app.com/api/v2/write?bucket=sensorbox&org=kota +I (13943) BME688: Delaying for 10000 microseconds +W (14430) wifi:Haven't to connect to a suitable AP now! +I (14563) KOTA: mic enabled=1 (GPIO3=0, expected 0) +I (14566) Flicker: Flicker sensor initialized on pin 14 +I (14567) temperature_sensor: Range [20°C ~ 100°C], error < 2°C +I (14569) SENSOR_GATHERER: Resumed 2 tasks to gather sensor data +W (14573) wifi: +I (14569) SENSOR_GATHERER: Resumed 4 tasks to gather sensor data +Haven't to connect to a suitable AP now! +I (14570) SEN66: Creating sensor mutex +I (14572) Flicker: Collecting 1024 samples at 1000 Hz for dominant frequency analysis for total measurement window of 1000 ms +I (14600) Flicker: Starting to collect samples in gather task +I (14585) DeviceStatus: Active tasks: 27 +I (14588) SEN66: Waking up sensor by setting power GPIO to enabled level... +I (14570) INFLUX_BUFFER: Buffer file does not exist, returning empty buffer +I (14622) INFLUX_BUFFER: Buffer is empty, nothing to flush +I (14613) DeviceStatus: Task list: +gather_sensor_t R 1 1920 25 +gather_sensor_t X 1 1368 23 +IDLE1 R 0 700 6 +IDLE0 R 0 692 5 +gather_sensor_t B 1 2556 22 +reset_task B 0 3356 10 +ds1307_update B 1 776 18 +tiT B 18 2420 12 +gather_task B 1 1916 20 +gather_task B 1 14288 21 +ota_task B 1 7516 19 +CHIP B 1 3700 14 +health B 1 728 17 +Tmr Svc B 1 1364 7 +led_task B 5 868 8 +nimble_host B 21 1348 16 +ipc0 S 24 372 1 +ipc1 S 24 524 2 +sys_evt B 20 2676 11 +gather_sensor_t B 1 3180 26 +esp_timer S 22 3044 3 +gather_sensor_t B 1 2324 24 +main B 1 892 4 +gather_sensor_t S 1 1480 27 +wifi B 23 3796 13 +btController B 23 1864 15 +flicker_gather_ S 24 1988 28 +usb_console B 1 2840 9 + +W (14702) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (15433) wifi:Haven't to connect to a suitable AP now! +I (15630) Flicker: Finished collecting samples, notifying main task +I (15632) Flicker: Collected 1024 samples for dominant frequency analysis +I (15639) SENSOR_GATHERER: Sensor should now be asleep +W (15650) AS7341: Reading spectral data from AS7341 +I (15659) BME688: Delaying for 199517 microseconds +W (15710) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (15958) AS7341: Reading basic counts data from AS7341 +W (15974) AS7341: Reading flicker detection state from AS7341 +W (16194) as7341: FD Status Register: 0x20 (0b00100000) +I (16201) AS7341: Updating Matter attributes with AS7341 data: 10474 +I (16202) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000400's Attribute 0x00000000 is 0 ********** +I (16208) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000400's Attribute 0x00000000 is 10474 ********** +E (16219) MAIN: [E-MAIN-03] Attribute update callback: endpoint_id: 1, cluster_id: 0x400, attribute_id: 0x0 +E (16229) MAIN: [E-MAIN-03] Attribute update callback: endpoint_id: 1, cluster_id: 0x400, attribute_id: 0x0 +W (16238) AS7341: RTC is uninitialized, using current time as timestamp +I (16241) esp_matter_attribute: ********** R : Endpoint 0x0001's Cluster 0x00000403's Attribute 0x00000000 is null ********** +I (16256) esp_matter_attribute: ********** W : Endpoint 0x0001's Cluster 0x00000403's Attribute 0x00000000 is 1021 ********** +E (16267) MAIN: [E-MAIN-03] Attribute update callback: endpoint_id: 1, cluster_id: 0x403, attribute_id: 0x0 +E (16276) MAIN: [E-MAIN-03] Attribute update callback: endpoint_id: 1, cluster_id: 0x403, attribute_id: 0x0 +W (16286) BME688: RTC is uninitialized, using current time as timestamp +W (16434) wifi:Haven't to connect to a suitable AP now! +W (16711) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (17183) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (17186) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (17187) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (17190) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (17198) wifi:Haven't to connect to a suitable AP now! +E (17203) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (17212) wifi:Haven't to connect to a suitable AP now! +I (17217) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (17224) wifi:Haven't to connect to a suitable AP now! +E (17229) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (17239) chip[DL]: Next WiFi station reconnect in 100 ms +I (17243) chip[DL]: Done driving station state, nothing else to do... +W (17244) AS7341: RTC is uninitialized, using current time as timestamp +W (17291) BME688: RTC is uninitialized, using current time as timestamp +W (17344) wifi:Haven't to connect to a suitable AP now! +I (17346) chip[DL]: Attempting to connect WiFi station interface +I (17349) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (17351) wifi:Haven't to connect to a suitable AP now! +E (17356) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (17366) chip[DL]: Done driving station state, nothing else to do... +W (17437) wifi:Haven't to connect to a suitable AP now! +W (17712) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (18256) AS7341: RTC is uninitialized, using current time as timestamp +W (18292) BME688: RTC is uninitialized, using current time as timestamp +W (18439) wifi:Haven't to connect to a suitable AP now! +W (18713) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (19257) AS7341: RTC is uninitialized, using current time as timestamp +W (19293) BME688: RTC is uninitialized, using current time as timestamp +W (19440) wifi:Haven't to connect to a suitable AP now! +I (19615) SEN66: Sent start measurement command, waiting for sensor to be ready... +W (19714) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (19928) KOTA: cmd ping ok=1 fw=1.0.16 api=1 +I (19933) KOTA: cmd wifi_get ok=1 ssid=- password_set=0 mode=matter +W (20258) AS7341: RTC is uninitialized, using current time as timestamp +W (20294) BME688: RTC is uninitialized, using current time as timestamp +W (20441) wifi:Haven't to connect to a suitable AP now! +W (20715) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (21259) AS7341: RTC is uninitialized, using current time as timestamp +W (21295) BME688: RTC is uninitialized, using current time as timestamp +W (21442) wifi:Haven't to connect to a suitable AP now! +W (21716) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (22166) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (22169) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (22170) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (22173) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (22181) wifi:Haven't to connect to a suitable AP now! +E (22186) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (22196) wifi:Haven't to connect to a suitable AP now! +I (22200) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (22207) wifi:Haven't to connect to a suitable AP now! +E (22212) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (22222) chip[DL]: Next WiFi station reconnect in 100 ms +I (22226) chip[DL]: Done driving station state, nothing else to do... +W (22260) AS7341: RTC is uninitialized, using current time as timestamp +W (22296) BME688: RTC is uninitialized, using current time as timestamp +W (22327) wifi:Haven't to connect to a suitable AP now! +I (22328) chip[DL]: Attempting to connect WiFi station interface +I (22332) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (22334) wifi:Haven't to connect to a suitable AP now! +E (22340) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (22349) chip[DL]: Done driving station state, nothing else to do... +W (22446) wifi:Haven't to connect to a suitable AP now! +I (22574) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (22717) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (23075) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (23261) AS7341: RTC is uninitialized, using current time as timestamp +W (23297) BME688: RTC is uninitialized, using current time as timestamp +W (23447) wifi:Haven't to connect to a suitable AP now! +I (23576) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (23718) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (24077) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (24262) AS7341: RTC is uninitialized, using current time as timestamp +W (24299) BME688: RTC is uninitialized, using current time as timestamp +W (24448) wifi:Haven't to connect to a suitable AP now! +I (24570) OTA_CLIENT: HTTP client handle: 0x3fcf02e4 +I (24578) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (24710) MICROPHONE: Read 600000 bytes in 9940 ms +I (24712) MICROPHONE: Read 600000 bytes of audio data +W (24719) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (25079) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (25264) AS7341: RTC is uninitialized, using current time as timestamp +W (25300) BME688: RTC is uninitialized, using current time as timestamp +W (25449) wifi:Haven't to connect to a suitable AP now! +I (25580) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (25720) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (26081) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (26265) AS7341: RTC is uninitialized, using current time as timestamp +W (26301) BME688: RTC is uninitialized, using current time as timestamp +W (26450) wifi:Haven't to connect to a suitable AP now! +I (26582) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (26721) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (27083) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (27150) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (27152) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (27154) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (27157) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (27164) wifi:Haven't to connect to a suitable AP now! +E (27169) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (27179) wifi:Haven't to connect to a suitable AP now! +I (27183) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (27190) wifi:Haven't to connect to a suitable AP now! +E (27196) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (27205) chip[DL]: Next WiFi station reconnect in 100 ms +I (27209) chip[DL]: Done driving station state, nothing else to do... +W (27266) AS7341: RTC is uninitialized, using current time as timestamp +W (27302) BME688: RTC is uninitialized, using current time as timestamp +W (27310) wifi:Haven't to connect to a suitable AP now! +I (27311) chip[DL]: Attempting to connect WiFi station interface +I (27315) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (27318) wifi:Haven't to connect to a suitable AP now! +E (27323) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (27332) chip[DL]: Done driving station state, nothing else to do... +W (27456) wifi:Haven't to connect to a suitable AP now! +I (27584) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (27722) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (28085) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (28160) MICROPHONE: Raw block stats: mean=-17.8 min=-146018 max=134018 +I (28161) MICROPHONE: Sum SQ: 0.578925, RMS pressure: 0.001701, Normalized: 0.001701, dB peak: -55.38 +W (28166) SEN66: RTC is uninitialized, using current time as timestamp +W (28267) AS7341: RTC is uninitialized, using current time as timestamp +W (28303) BME688: RTC is uninitialized, using current time as timestamp +W (28457) wifi:Haven't to connect to a suitable AP now! +I (28586) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (28723) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (29087) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (29172) SEN66: RTC is uninitialized, using current time as timestamp +W (29268) AS7341: RTC is uninitialized, using current time as timestamp +W (29304) BME688: RTC is uninitialized, using current time as timestamp +W (29458) wifi:Haven't to connect to a suitable AP now! +I (29588) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (29724) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (30089) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (30173) SEN66: RTC is uninitialized, using current time as timestamp +W (30269) AS7341: RTC is uninitialized, using current time as timestamp +W (30305) BME688: RTC is uninitialized, using current time as timestamp +W (30459) wifi:Haven't to connect to a suitable AP now! +I (30590) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (30725) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (31091) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (31174) SEN66: RTC is uninitialized, using current time as timestamp +W (31270) AS7341: RTC is uninitialized, using current time as timestamp +W (31306) BME688: RTC is uninitialized, using current time as timestamp +W (31460) wifi:Haven't to connect to a suitable AP now! +E (31572) esp-tls: couldn't get hostname for :bin.kota-app.com: getaddrinfo() returns 202, addrinfo=0x0 +E (31573) esp-tls: Failed to open new connection +E (31575) transport_base: Failed to open a new connection +E (31580) HTTP_CLIENT: Connection failed, sock < 0 +E (31585) OTA_CLIENT: [E-OTA-01] Error ESP_ERR_HTTP_CONNECT at ./main/ota_client.cpp:355 +I (31592) SENSOR_GATHERER: 2/2 tasks still running, waiting... +E (31593) OTA_CLIENT: [E-OTA-13] Failed to check for updates: ESP_ERR_HTTP_CONNECT +I (31606) KOTA: status OK -> CONNECTION_ERROR reason=ota_check_failed +W (31726) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (32098) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (32133) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (32135) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (32137) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (32140) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (32147) wifi:Haven't to connect to a suitable AP now! +E (32152) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (32162) wifi:Haven't to connect to a suitable AP now! +I (32166) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (32173) wifi:Haven't to connect to a suitable AP now! +W (32175) SEN66: RTC is uninitialized, using current time as timestamp +E (32179) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (32194) chip[DL]: Next WiFi station reconnect in 100 ms +I (32199) chip[DL]: Done driving station state, nothing else to do... +W (32271) AS7341: RTC is uninitialized, using current time as timestamp +W (32300) wifi:Haven't to connect to a suitable AP now! +I (32301) chip[DL]: Attempting to connect WiFi station interface +I (32305) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (32307) BME688: RTC is uninitialized, using current time as timestamp +W (32307) wifi:Haven't to connect to a suitable AP now! +E (32319) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (32328) chip[DL]: Done driving station state, nothing else to do... +W (32462) wifi:Haven't to connect to a suitable AP now! +I (32599) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (32727) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (32730) chip[DL]: bleAdv Timeout : Start slow advertisement +I (32732) chip[DL]: Configuring CHIPoBLE advertising (interval 500 ms, connectable) +I (32737) chip[DL]: Device already advertising, stop active advertisement and restart +I (32744) NimBLE: GAP procedure initiated: stop advertising. + +I (32750) NimBLE: GAP procedure initiated: advertise; +I (32754) NimBLE: disc_mode=2 +I (32756) NimBLE: adv_channel_map=0 own_addr_type=1 adv_filter_policy=0 adv_itvl_min=800 adv_itvl_max=800 +I (32767) NimBLE: + +I (33100) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (33184) SEN66: RTC is uninitialized, using current time as timestamp +W (33272) AS7341: RTC is uninitialized, using current time as timestamp +W (33320) BME688: RTC is uninitialized, using current time as timestamp +W (33463) wifi:Haven't to connect to a suitable AP now! +I (33601) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (33728) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (34102) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (34185) SEN66: RTC is uninitialized, using current time as timestamp +W (34274) AS7341: RTC is uninitialized, using current time as timestamp +W (34321) BME688: RTC is uninitialized, using current time as timestamp +W (34464) wifi:Haven't to connect to a suitable AP now! +I (34603) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (34729) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (35104) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (35186) SEN66: RTC is uninitialized, using current time as timestamp +W (35275) AS7341: RTC is uninitialized, using current time as timestamp +W (35322) BME688: RTC is uninitialized, using current time as timestamp +W (35465) wifi:Haven't to connect to a suitable AP now! +I (35605) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (35730) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (36106) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (36187) SEN66: RTC is uninitialized, using current time as timestamp +W (36276) AS7341: RTC is uninitialized, using current time as timestamp +W (36323) BME688: RTC is uninitialized, using current time as timestamp +W (36466) wifi:Haven't to connect to a suitable AP now! +I (36607) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (36731) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (37107) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (37122) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (37125) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (37126) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (37129) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (37137) wifi:Haven't to connect to a suitable AP now! +E (37142) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (37151) wifi:Haven't to connect to a suitable AP now! +I (37156) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (37163) wifi:Haven't to connect to a suitable AP now! +E (37168) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (37178) chip[DL]: Next WiFi station reconnect in 100 ms +I (37182) chip[DL]: Done driving station state, nothing else to do... +W (37188) SEN66: RTC is uninitialized, using current time as timestamp +W (37277) AS7341: RTC is uninitialized, using current time as timestamp +W (37283) wifi:Haven't to connect to a suitable AP now! +I (37284) chip[DL]: Attempting to connect WiFi station interface +I (37287) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (37290) wifi:Haven't to connect to a suitable AP now! +E (37295) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (37305) chip[DL]: Done driving station state, nothing else to do... +W (37324) BME688: RTC is uninitialized, using current time as timestamp +W (37468) wifi:Haven't to connect to a suitable AP now! +I (37608) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (37732) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (38109) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (38195) SEN66: RTC is uninitialized, using current time as timestamp +W (38278) AS7341: RTC is uninitialized, using current time as timestamp +W (38325) BME688: RTC is uninitialized, using current time as timestamp +W (38470) wifi:Haven't to connect to a suitable AP now! +I (38610) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (38733) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (39111) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (39196) SEN66: RTC is uninitialized, using current time as timestamp +W (39279) AS7341: RTC is uninitialized, using current time as timestamp +W (39326) BME688: RTC is uninitialized, using current time as timestamp +W (39471) wifi:Haven't to connect to a suitable AP now! +I (39612) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (39736) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (40113) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (40197) SEN66: RTC is uninitialized, using current time as timestamp +W (40280) AS7341: RTC is uninitialized, using current time as timestamp +W (40326) BME688: RTC is uninitialized, using current time as timestamp +W (40473) wifi:Haven't to connect to a suitable AP now! +I (40614) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (40737) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (41115) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (41198) SEN66: RTC is uninitialized, using current time as timestamp +W (41281) AS7341: RTC is uninitialized, using current time as timestamp +W (41327) BME688: RTC is uninitialized, using current time as timestamp +W (41474) wifi:Haven't to connect to a suitable AP now! +I (41616) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (41738) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (42105) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (42108) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (42109) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (42112) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +I (42116) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (42120) wifi:Haven't to connect to a suitable AP now! +E (42130) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (42140) wifi:Haven't to connect to a suitable AP now! +I (42144) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (42151) wifi:Haven't to connect to a suitable AP now! +E (42156) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (42166) chip[DL]: Next WiFi station reconnect in 100 ms +I (42170) chip[DL]: Done driving station state, nothing else to do... +W (42199) SEN66: RTC is uninitialized, using current time as timestamp +W (42271) wifi:Haven't to connect to a suitable AP now! +I (42272) chip[DL]: Attempting to connect WiFi station interface +I (42275) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (42278) wifi:Haven't to connect to a suitable AP now! +W (42283) AS7341: RTC is uninitialized, using current time as timestamp +E (42283) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (42299) chip[DL]: Done driving station state, nothing else to do... +W (42328) BME688: RTC is uninitialized, using current time as timestamp +W (42476) wifi:Haven't to connect to a suitable AP now! +I (42625) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (42739) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (43126) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (43200) SEN66: RTC is uninitialized, using current time as timestamp +W (43290) AS7341: RTC is uninitialized, using current time as timestamp +W (43329) BME688: RTC is uninitialized, using current time as timestamp +W (43482) wifi:Haven't to connect to a suitable AP now! +I (43627) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (43740) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (44128) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (44201) SEN66: RTC is uninitialized, using current time as timestamp +W (44291) AS7341: RTC is uninitialized, using current time as timestamp +W (44330) BME688: RTC is uninitialized, using current time as timestamp +W (44486) wifi:Haven't to connect to a suitable AP now! +I (44629) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (44741) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (45130) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (45202) SEN66: RTC is uninitialized, using current time as timestamp +W (45292) AS7341: RTC is uninitialized, using current time as timestamp +W (45331) BME688: RTC is uninitialized, using current time as timestamp +W (45487) wifi:Haven't to connect to a suitable AP now! +I (45631) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (45742) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (46132) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (46203) SEN66: RTC is uninitialized, using current time as timestamp +W (46293) AS7341: RTC is uninitialized, using current time as timestamp +W (46332) BME688: RTC is uninitialized, using current time as timestamp +W (46488) wifi:Haven't to connect to a suitable AP now! +I (46633) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (46743) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (47093) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (47095) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (47097) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (47100) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (47107) wifi:Haven't to connect to a suitable AP now! +E (47112) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (47122) wifi:Haven't to connect to a suitable AP now! +I (47126) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +I (47134) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (47134) wifi:Haven't to connect to a suitable AP now! +E (47144) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (47154) chip[DL]: Next WiFi station reconnect in 100 ms +I (47158) chip[DL]: Done driving station state, nothing else to do... +W (47204) SEN66: RTC is uninitialized, using current time as timestamp +W (47259) wifi:Haven't to connect to a suitable AP now! +I (47260) chip[DL]: Attempting to connect WiFi station interface +I (47263) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (47266) wifi:Haven't to connect to a suitable AP now! +E (47271) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (47281) chip[DL]: Done driving station state, nothing else to do... +W (47294) AS7341: RTC is uninitialized, using current time as timestamp +W (47333) BME688: RTC is uninitialized, using current time as timestamp +W (47493) wifi:Haven't to connect to a suitable AP now! +I (47639) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (47744) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (48140) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (48205) SEN66: RTC is uninitialized, using current time as timestamp +W (48295) AS7341: RTC is uninitialized, using current time as timestamp +W (48334) BME688: RTC is uninitialized, using current time as timestamp +W (48495) wifi:Haven't to connect to a suitable AP now! +I (48641) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (48747) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (49142) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (49206) SEN66: RTC is uninitialized, using current time as timestamp +W (49296) AS7341: RTC is uninitialized, using current time as timestamp +W (49335) BME688: RTC is uninitialized, using current time as timestamp +W (49496) wifi:Haven't to connect to a suitable AP now! +I (49643) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (49748) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (50144) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (50207) SEN66: RTC is uninitialized, using current time as timestamp +W (50297) AS7341: RTC is uninitialized, using current time as timestamp +W (50336) BME688: RTC is uninitialized, using current time as timestamp +W (50498) wifi:Haven't to connect to a suitable AP now! +I (50645) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (50749) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (51146) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (51208) SEN66: RTC is uninitialized, using current time as timestamp +W (51298) AS7341: RTC is uninitialized, using current time as timestamp +W (51337) BME688: RTC is uninitialized, using current time as timestamp +W (51500) wifi:Haven't to connect to a suitable AP now! +I (51647) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (51750) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (52081) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (52084) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (52085) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (52088) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (52096) wifi:Haven't to connect to a suitable AP now! +E (52100) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (52110) wifi:Haven't to connect to a suitable AP now! +I (52115) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (52122) wifi:Haven't to connect to a suitable AP now! +E (52127) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (52136) chip[DL]: Next WiFi station reconnect in 100 ms +I (52141) chip[DL]: Done driving station state, nothing else to do... +I (52148) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (52209) SEN66: RTC is uninitialized, using current time as timestamp +W (52242) wifi:Haven't to connect to a suitable AP now! +I (52243) chip[DL]: Attempting to connect WiFi station interface +I (52246) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (52249) wifi:Haven't to connect to a suitable AP now! +E (52254) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (52264) chip[DL]: Done driving station state, nothing else to do... +W (52299) AS7341: RTC is uninitialized, using current time as timestamp +W (52338) BME688: RTC is uninitialized, using current time as timestamp +W (52508) wifi:Haven't to connect to a suitable AP now! +I (52653) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (52751) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (53154) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (53210) SEN66: RTC is uninitialized, using current time as timestamp +W (53300) AS7341: RTC is uninitialized, using current time as timestamp +W (53339) BME688: RTC is uninitialized, using current time as timestamp +W (53509) wifi:Haven't to connect to a suitable AP now! +I (53655) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (53752) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (54156) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (54212) SEN66: RTC is uninitialized, using current time as timestamp +W (54301) AS7341: RTC is uninitialized, using current time as timestamp +W (54340) BME688: RTC is uninitialized, using current time as timestamp +W (54510) wifi:Haven't to connect to a suitable AP now! +I (54657) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (54753) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (55158) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (55213) SEN66: RTC is uninitialized, using current time as timestamp +W (55302) AS7341: RTC is uninitialized, using current time as timestamp +W (55341) BME688: RTC is uninitialized, using current time as timestamp +W (55511) wifi:Haven't to connect to a suitable AP now! +I (55659) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (55754) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (56160) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (56214) SEN66: RTC is uninitialized, using current time as timestamp +W (56303) AS7341: RTC is uninitialized, using current time as timestamp +W (56342) BME688: RTC is uninitialized, using current time as timestamp +W (56512) wifi:Haven't to connect to a suitable AP now! +I (56661) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (56755) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (57064) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (57067) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (57068) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (57071) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (57079) wifi:Haven't to connect to a suitable AP now! +E (57083) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (57093) wifi:Haven't to connect to a suitable AP now! +I (57097) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (57105) wifi:Haven't to connect to a suitable AP now! +E (57110) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (57119) chip[DL]: Next WiFi station reconnect in 100 ms +I (57124) chip[DL]: Done driving station state, nothing else to do... +I (57162) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (57215) SEN66: RTC is uninitialized, using current time as timestamp +W (57225) wifi:Haven't to connect to a suitable AP now! +I (57226) chip[DL]: Attempting to connect WiFi station interface +I (57230) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (57232) wifi:Haven't to connect to a suitable AP now! +E (57237) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (57247) chip[DL]: Done driving station state, nothing else to do... +W (57304) AS7341: RTC is uninitialized, using current time as timestamp +W (57344) BME688: RTC is uninitialized, using current time as timestamp +W (57517) wifi:Haven't to connect to a suitable AP now! +I (57663) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (57756) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (58164) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (58216) SEN66: RTC is uninitialized, using current time as timestamp +W (58305) AS7341: RTC is uninitialized, using current time as timestamp +W (58345) BME688: RTC is uninitialized, using current time as timestamp +W (58518) wifi:Haven't to connect to a suitable AP now! +I (58665) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (58757) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (59166) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (59217) SEN66: RTC is uninitialized, using current time as timestamp +W (59306) AS7341: RTC is uninitialized, using current time as timestamp +W (59346) BME688: RTC is uninitialized, using current time as timestamp +W (59519) wifi:Haven't to connect to a suitable AP now! +I (59667) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (59758) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (60168) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (60218) SEN66: RTC is uninitialized, using current time as timestamp +W (60307) AS7341: RTC is uninitialized, using current time as timestamp +W (60346) BME688: RTC is uninitialized, using current time as timestamp +W (60520) wifi:Haven't to connect to a suitable AP now! +I (60669) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (60759) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (61170) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (61219) SEN66: RTC is uninitialized, using current time as timestamp +W (61308) AS7341: RTC is uninitialized, using current time as timestamp +W (61347) BME688: RTC is uninitialized, using current time as timestamp +W (61521) wifi:Haven't to connect to a suitable AP now! +I (61671) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (61760) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (62047) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (62050) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (62051) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (62054) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (62062) wifi:Haven't to connect to a suitable AP now! +E (62067) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (62076) wifi:Haven't to connect to a suitable AP now! +I (62081) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (62088) wifi:Haven't to connect to a suitable AP now! +E (62093) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (62102) chip[DL]: Next WiFi station reconnect in 100 ms +I (62107) chip[DL]: Done driving station state, nothing else to do... +I (62172) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (62208) wifi:Haven't to connect to a suitable AP now! +I (62209) chip[DL]: Attempting to connect WiFi station interface +I (62212) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (62215) wifi:Haven't to connect to a suitable AP now! +W (62220) SEN66: RTC is uninitialized, using current time as timestamp +E (62220) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (62236) chip[DL]: Done driving station state, nothing else to do... +W (62309) AS7341: RTC is uninitialized, using current time as timestamp +W (62348) BME688: RTC is uninitialized, using current time as timestamp +W (62527) wifi:Haven't to connect to a suitable AP now! +I (62673) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (62761) DeviceStatus: RTC is uninitialized, using current time as timestamp +W (62925) wifi:Haven't to connect to a suitable AP now! +I (62925) KOTA: health up=61s status=CONNECTION_ERROR wifi=disconnected rssi=0 time_valid=0 sd=ok buffer=0 heap=47259 psram=7.3M last_upload=never ota=registered +I (63174) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (63227) SEN66: RTC is uninitialized, using current time as timestamp +W (63310) AS7341: RTC is uninitialized, using current time as timestamp +W (63349) BME688: RTC is uninitialized, using current time as timestamp +W (63528) wifi:Haven't to connect to a suitable AP now! +I (63675) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (63762) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (64176) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (64228) SEN66: RTC is uninitialized, using current time as timestamp +W (64311) AS7341: RTC is uninitialized, using current time as timestamp +W (64350) BME688: RTC is uninitialized, using current time as timestamp +W (64529) wifi:Haven't to connect to a suitable AP now! +I (64617) SEN66: First measurement, waiting additional 120 seconds for sensor to stabilize... +I (64677) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (64763) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (65178) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (65229) SEN66: RTC is uninitialized, using current time as timestamp +W (65312) AS7341: RTC is uninitialized, using current time as timestamp +W (65351) BME688: RTC is uninitialized, using current time as timestamp +W (65530) wifi:Haven't to connect to a suitable AP now! +I (65679) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (65764) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (66180) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (66230) SEN66: RTC is uninitialized, using current time as timestamp +W (66313) AS7341: RTC is uninitialized, using current time as timestamp +W (66352) BME688: RTC is uninitialized, using current time as timestamp +W (66531) wifi:Haven't to connect to a suitable AP now! +I (66681) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (66765) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (67030) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (67033) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (67034) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (67037) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (67045) wifi:Haven't to connect to a suitable AP now! +E (67049) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (67059) wifi:Haven't to connect to a suitable AP now! +I (67063) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (67071) wifi:Haven't to connect to a suitable AP now! +E (67076) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (67085) chip[DL]: Next WiFi station reconnect in 100 ms +I (67090) chip[DL]: Done driving station state, nothing else to do... +I (67182) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (67191) wifi:Haven't to connect to a suitable AP now! +I (67192) chip[DL]: Attempting to connect WiFi station interface +I (67195) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (67198) wifi:Haven't to connect to a suitable AP now! +E (67203) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (67213) chip[DL]: Done driving station state, nothing else to do... +W (67231) SEN66: RTC is uninitialized, using current time as timestamp +W (67314) AS7341: RTC is uninitialized, using current time as timestamp +W (67353) BME688: RTC is uninitialized, using current time as timestamp +W (67537) wifi:Haven't to connect to a suitable AP now! +I (67683) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (67766) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (68184) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (68232) SEN66: RTC is uninitialized, using current time as timestamp +W (68315) AS7341: RTC is uninitialized, using current time as timestamp +W (68354) BME688: RTC is uninitialized, using current time as timestamp +W (68542) wifi:Haven't to connect to a suitable AP now! +I (68685) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (68767) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (69186) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (69233) SEN66: RTC is uninitialized, using current time as timestamp +W (69316) AS7341: RTC is uninitialized, using current time as timestamp +W (69355) BME688: RTC is uninitialized, using current time as timestamp +W (69546) wifi:Haven't to connect to a suitable AP now! +I (69687) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (69768) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (70188) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (70234) SEN66: RTC is uninitialized, using current time as timestamp +W (70317) AS7341: RTC is uninitialized, using current time as timestamp +W (70356) BME688: RTC is uninitialized, using current time as timestamp +W (70547) wifi:Haven't to connect to a suitable AP now! +I (70689) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (70769) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (71190) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (71235) SEN66: RTC is uninitialized, using current time as timestamp +W (71318) AS7341: RTC is uninitialized, using current time as timestamp +W (71357) BME688: RTC is uninitialized, using current time as timestamp +W (71548) wifi:Haven't to connect to a suitable AP now! +I (71691) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (71770) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (72013) wifi:Coexist: Wi-Fi connect fail, apply reconnect coex policy + +I (72015) chip[DL]: Posting ESPSystemEvent: Wifi Event with eventId : 5 +I (72017) chip[DL]: WIFI_EVENT_STA_DISCONNECTED +I (72020) chip[DL]: WiFi station state change: Connecting -> Connecting_Failed +W (72027) wifi:Haven't to connect to a suitable AP now! +E (72032) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +W (72042) wifi:Haven't to connect to a suitable AP now! +I (72046) chip[DL]: WiFi station state change: Connecting_Failed -> NotConnected +W (72054) wifi:Haven't to connect to a suitable AP now! +E (72059) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (72068) chip[DL]: Next WiFi station reconnect in 100 ms +I (72073) chip[DL]: Done driving station state, nothing else to do... +W (72173) wifi:Haven't to connect to a suitable AP now! +I (72175) chip[DL]: Attempting to connect WiFi station interface +I (72178) chip[DL]: WiFi station state change: NotConnected -> Connecting +W (72181) wifi:Haven't to connect to a suitable AP now! +E (72185) chip[DL]: Failed to get configured network when updating network status: Error ESP32:0x0500300F +I (72191) SENSOR_GATHERER: 2/2 tasks still running, waiting... +I (72195) chip[DL]: Done driving station state, nothing else to do... +W (72236) SEN66: RTC is uninitialized, using current time as timestamp +W (72319) AS7341: RTC is uninitialized, using current time as timestamp +W (72358) BME688: RTC is uninitialized, using current time as timestamp +W (72552) wifi:Haven't to connect to a suitable AP now! +I (72700) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (72771) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (73201) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (73237) SEN66: RTC is uninitialized, using current time as timestamp +W (73320) AS7341: RTC is uninitialized, using current time as timestamp +W (73359) BME688: RTC is uninitialized, using current time as timestamp +W (73553) wifi:Haven't to connect to a suitable AP now! +I (73702) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (73772) DeviceStatus: RTC is uninitialized, using current time as timestamp +I (74203) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (74238) SEN66: RTC is uninitialized, using current time as timestamp +W (74321) AS7341: RTC is uninitialized, using current time as timestamp +W (74360) BME688: RTC is uninitialized, using current time as timestamp +W (74555) wifi:Haven't to connect to a suitable AP now! +I (74581) SENSOR_GATHERER: 3/4 tasks still running, waiting... +I (74627) INFLUX_BUFFER: Buffer file does not exist, returning empty buffer +I (74628) INFLUX_BUFFER: Buffer is empty, nothing to flush +I (74704) SENSOR_GATHERER: 2/2 tasks still running, waiting... +W (74773) DeviceStatus: RTC is uninitialized, using current time as timestamp +--- disconnected --- \ No newline at end of file diff --git a/tests/test_commands.py b/tests/test_commands.py index 4b97c96..7c1f042 100644 --- a/tests/test_commands.py +++ b/tests/test_commands.py @@ -160,3 +160,18 @@ def test_engine_treats_usb_wifi_restart_as_intentional_and_reads_replies(): box = dict((t, x) for t, x, _ in r.box) assert "network 'Biuro'" in box["Wi-Fi config"] and "Wi-Fi connected (-60 dBm)" in box["Health"] assert any(m == "wifi_connected" and t is not None for m, _, t in r.milestones) + + +def test_live_v13_box_answers_usb_commands(): + # Real capture (2026-09-29): box 14:C1:9F:57:72:48 after flashing merged-binary-v13.bin App-only; + # the Wi-Fi tab's read flow sent "kota ping" and "kota wifi get". + from pathlib import Path + + from kota_tech.core.logfile import read_log_file + e = DiagnosticsEngine() + for ln in read_log_file(Path(__file__).parent / "fixtures" / "live_boot_14C19F577248_v13_usb_console.txt"): + e.feed(ln) + r = e.report() + assert e.firmware.elf_sha.startswith("3ae273ec") and e.console_api == 1 + assert dict((t, x) for t, x, _ in r.box)["Wi-Fi config"] == "no network on the SD card, mode matter" + assert not any(f.rule_id.startswith("other.") for f in e.findings.values()) # logs intact via the driver