Executing action: flash Serial port /dev/ttyUSB0 Connecting..... Detecting chip type... Unsupported detection protocol, switching and trying again... Connecting.... Detecting chip type...[1/10] Performing build step for 'chip_gn' ninja: no work to do. [2/6] cd /home/damian/Projects/mill/build_mill_esp32/esp-idf/esptool_py && /home/damian/.espressif/python_env/idf4.4_py3.8_env/bin/python /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/components/partition_table/check_sizes.py --offset 0x8000 partition --type app /home/damian/Projects/mill/build_mill_esp32/partition_table/partition-table.bin /home/damian/Projects/mill/build_mill_esp32/light.bin Warning: 1/2 app partitions are too small for binary light.bin size 0x1be5c0: - Part 'ota_1' 0/17 @ 0x200000 size 0xb0000 (overflow 0x10e5c0) [3/6] Performing build step for 'bootloader' [1/1] cd /home/damian/Projects/mill/build_mill_esp32/bootloader/esp-idf/esptool_py && /home/damian/.espressif/python_env/idf4.4_py3.8_env/bin/python /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/components/partition_table/check_sizes.py --offset 0x8000 bootloader 0x1000 /home/damian/Projects/mill/build_mill_esp32/bootloader/bootloader.bin Bootloader binary size 0x5d80 bytes. 0x1280 bytes (17%) free. [3/4] cd /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/components/esptool_py && /usr/bin/cmake -D IDF_PATH="/home/damian/Projects/mill/mill-esp32/main/externals/esp-idf" -D SERIAL_TOOL="/home/damian/.espressif/python_env/idf4.4_py3.8_env/bin/python /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/components/esptool_py/esptool/esptool.py --chip esp32" -D SERIAL_TOOL_ARGS="--before=default_reset --after=hard_reset write_flash @flash_args" -D WORKING_DIRECTORY="/home/damian/Projects/mill/build_mill_esp32" -P /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/components/esptool_py/run_serial_tool.cmake esptool.py esp32 -p /dev/ttyUSB0 -b 460800 --before=default_reset --after=hard_reset write_flash --flash_mode dio --flash_freq 40m --flash_size 4MB 0x1000 bootloader/bootloader.bin 0x10000 light.bin 0x8000 partition_table/partition-table.bin 0xd000 ota_data_initial.bin esptool.py v3.3.2 Serial port /dev/ttyUSB0 Connecting..... Chip is ESP32-D0WDQ6 (revision 1) Features: WiFi, BT, Dual Core, 240MHz, VRef calibration in efuse, Coding Scheme None WARNING: Detected crystal freq 41.01MHz is quite different to normalized freq 40MHz. Unsupported crystal in use? Crystal is 40MHz MAC: 08:3a:f2:ab:40:f8 Uploading stub... Running stub... Stub running... Changing baud rate to 460800 Changed. Configuring flash size... Flash will be erased from 0x00001000 to 0x00006fff... Flash will be erased from 0x00010000 to 0x001cefff... Flash will be erased from 0x00008000 to 0x00008fff... Flash will be erased from 0x0000d000 to 0x0000efff... Compressed 23936 bytes to 15154... Writing at 0x00001000... (100 %) Wrote 23936 bytes (15154 compressed) at 0x00001000 in 0.8 seconds (effective 247.7 kbit/s)... Hash of data verified. Compressed 1828288 bytes to 1164732... Writing at 0x00010000... (1 %) Writing at 0x0001d7d6... (2 %) Writing at 0x0002a36e... (4 %) Writing at 0x000360a3... (5 %) Writing at 0x0003c387... (6 %) Writing at 0x0004587a... (8 %) Writing at 0x0004f6fc... (9 %) Writing at 0x0005a90a... (11 %) Writing at 0x00066fea... (12 %) Writing at 0x00071361... (13 %) Writing at 0x000772ca... (15 %) Writing at 0x0007c68b... (16 %) Writing at 0x00081cd3... (18 %) Writing at 0x0008786c... (19 %) Writing at 0x0008d064... (20 %) Writing at 0x0009285e... (22 %) Writing at 0x00098063... (23 %) Writing at 0x0009d40c... (25 %) Writing at 0x000a2e34... (26 %) Writing at 0x000a9fc4... (27 %) Writing at 0x000afcd5... (29 %) Writing at 0x000b5458... (30 %) Writing at 0x000bb4c7... (31 %) Writing at 0x000c0a0c... (33 %) Writing at 0x000c5e2e... (34 %) Writing at 0x000cbb6c... (36 %) Writing at 0x000d10e5... (37 %) Writing at 0x000d6e99... (38 %) Writing at 0x000dc382... (40 %) Writing at 0x000e1c49... (41 %) Writing at 0x000e7bdc... (43 %) Writing at 0x000edf3c... (44 %) Writing at 0x000f3e3d... (45 %) Writing at 0x000f9680... (47 %) Writing at 0x000ff13b... (48 %) Writing at 0x00104794... (50 %) Writing at 0x00109fe9... (51 %) Writing at 0x0010fec1... (52 %) Writing at 0x00115180... (54 %) Writing at 0x0011a8ef... (55 %) Writing at 0x0011fdba... (56 %) Writing at 0x0012508d... (58 %) Writing at 0x0012aeb4... (59 %) Writing at 0x001303f4... (61 %) Writing at 0x00135d01... (62 %) Writing at 0x0013b5e4... (63 %) Writing at 0x00140cdd... (65 %) Writing at 0x001460b9... (66 %) Writing at 0x0014afe4... (68 %) Writing at 0x0015012b... (69 %) Writing at 0x001551e6... (70 %) Writing at 0x0015a7ac... (72 %) Writing at 0x0015f6cb... (73 %) Writing at 0x00164a65... (75 %) Writing at 0x00169d03... (76 %) Writing at 0x0016f1df... (77 %) Writing at 0x001743e2... (79 %) Writing at 0x00179a6f... (80 %) Writing at 0x0017f233... (81 %) Writing at 0x00184e14... (83 %) Writing at 0x00189cf7... (84 %) Writing at 0x0018f162... (86 %) Writing at 0x00194c41... (87 %) Writing at 0x0019be3a... (88 %) Writing at 0x001a3da8... (90 %) Writing at 0x001ab543... (91 %) Writing at 0x001b1fc0... (93 %) Writing at 0x001b79f8... (94 %) Writing at 0x001bd5aa... (95 %) Writing at 0x001c2f48... (97 %) Writing at 0x001c878b... (98 %) Writing at 0x001cdcd0... (100 %) Wrote 1828288 bytes (1164732 compressed) at 0x00010000 in 26.7 seconds (effective 548.7 kbit/s)... Hash of data verified. Compressed 3072 bytes to 143... Writing at 0x00008000... (100 %) Wrote 3072 bytes (143 compressed) at 0x00008000 in 0.1 seconds (effective 377.5 kbit/s)... Hash of data verified. Compressed 8192 bytes to 31... Writing at 0x0000d000... (100 %) Wrote 8192 bytes (31 compressed) at 0x0000d000 in 0.1 seconds (effective 569.8 kbit/s)... Hash of data verified. Leaving... Hard resetting via RTS pin... ESP32 Running ninja in directory /home/damian/Projects/mill/build_mill_esp32 Executing "ninja flash"... Executing action: monitor Serial port /dev/ttyUSB0 Connecting..... Detecting chip type... Unsupported detection protocol, switching and trying again... Connecting...... Detecting chip type...ets Jun 8 2016 00:22:57 rst:0x1 (POWERON_RESET),boot:0x13 (SPI_FAST_FLASH_BOOT) configsip: 0, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:2 load:0x3fff0030,len:5664 load:0x40078000,len:14500 ho 0 tail 12 room 4 load:0x40080400,len:3680 entry 0x40080674 I (28) boot: ESP-IDF v4.4.3-dirty 2nd stage bootloader I (28) boot: compile time 15:30:53 I (28) boot: chip revision: 1 I (32) boot_comm: chip revision: 1, min. bootloader chip revision: 0 I (39) boot.esp32: SPI Speed : 40MHz I (43) boot.esp32: SPI Mode : DIO I (48) boot.esp32: SPI Flash Size : 4MB I (52) boot: Enabling RNG early entropy source... I (58) boot: Partition Table: I (61) boot: ## Label Usage Type ST Offset Length I (69) boot: 0 otadata OTA data 01 00 0000d000 00002000 I (76) boot: 1 phy_init RF data 01 01 0000f000 00001000 I (84) boot: 2 ota_0 OTA app 00 10 00010000 001f0000 I (91) boot: 3 ota_1 OTA app 00 11 00200000 000b0000 I (98) boot: 4 ac_data unknown 40 00 00370000 00001000 I (106) boot: 5 nvs WiFi data 01 02 00371000 00030000 I (114) boot: End of partition table I (118) boot_comm: chip revision: 1, min. application chip revision: 0 I (125) esp_image: segment 0: paddr=00010020 vaddr=3f400020 size=58074h (360564) map I (263) esp_image: segment 1: paddr=0006809c vaddr=3ff80063 size=00008h ( 8) load I (263) esp_image: segment 2: paddr=000680ac vaddr=3ffbdb60 size=0560ch ( 22028) load I (277) esp_image: segment 3: paddr=0006d6c0 vaddr=40080000 size=02958h ( 10584) load I (282) esp_image: segment 4: paddr=00070020 vaddr=400d0020 size=144314h (1327892) map I (762) esp_image: segment 5: paddr=001b433c vaddr=40082958 size=1a1d0h (106960) load I (805) esp_image: segment 6: paddr=001ce514 vaddr=400c0000 size=00064h ( 100) load I (806) esp_image: segment 7: paddr=001ce580 vaddr=50000000 size=00010h ( 16) load I (825) boot: Loaded app from partition at offset 0x10000 I (826) boot: Disabling RNG early entropy source... I (837) cpu_start: Pro cpu up. I (838) cpu_start: Starting app cpu, entry point is 0x40080f8c I (0) cpu_start: App cpu up. I (854) cpu_start: Pro cpu start user code I (854) cpu_start: cpu freq: 160000000 I (854) cpu_start: Application information: I (858) cpu_start: Project name: light I (863) cpu_start: App version: v1.0 I (868) cpu_start: Compile time: Sep 5 2023 10:08:38 I (874) cpu_start: ELF file SHA256: 2ba4346e6e23f76b... I (880) cpu_start: ESP-IDF: v4.4.3-dirty I (885) heap_init: Initializing. RAM available for dynamic allocation: I (892) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (898) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (904) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (911) heap_init: At 3FFDA1E8 len 00005E18 (23 KiB): DRAM I (917) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (923) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (930) heap_init: At 4009CB28 len 000034D8 (13 KiB): IRAM I (937) spi_flash: detected chip: generic I (940) spi_flash: flash io: dio I (948) cpu_start: Starting scheduler on PRO CPU. I (0) cpu_start: Starting scheduler on APP CPU. I (1176) Main: Firmware type: PRODUCTION (USER FIRMWARE) I (1176) Config: Opening Non-Volatile Storage (NVS) handle...  I (1176) Config: NVS statistics: UsedEntries = 182, FreeEntries = 5866, AllEntries = 6048, namespace_count = 11  W (1196) Config_heat: Current ssid from config: 5b6cb4 W (1196) Config_heat: Current pass from config: 244823546 I (1206) Config: NOT Detected safe reset I (1206) Config: setWifiSsid - written 5b6cb4 to config I (1216) Config: setWifiPassword - written 244823546 to config I (1216) Main_h: Reset reason: 1 I (1226) TemperatureMeasurement: temperature_measurement - init started... I (1426) NtpTime: NTP init I (1426) App: init started... I (1426) App: Using regulator (controller): PID I (1426) App: Using regulator (controller): Hysteresis I (1426) HysteresisController: Hysteresis driver initialized, with parameters upperTemperatureThreshold = 1.000000 lowerTemperatureThreshold = 0.500000 isHeatingNow = 0 I (1446) UI: init started... I (1476) WiFiHandler: WIFI interface initialized correctly I (1476) WiFiController: turnOnWifiStaPowerSaving(0) I (1476) CloudSwitcher: initializeCurrentCloudProvider: 1 I (1476) CloudSwitcher: Allow AWS cloud creation - init switcher W (1486) aws_cloud_handler: CloudHandler - allow clas creation I (1496) TemperatureMeasurement: runTask started... I (1496) Display: runTask started... I (1506) App: runTasks started... I (1506) App: runRegulator started... I (1506) Misc: connectToWiFiOrStartApDependingOnConfiguration started... I (1506) UI: runTasks started... I (1516) WiFiController: configureNewWiFiCredentials(5b6cb4, 244823546, 0) I (1526) UI: run started... I (1526) WiFiController: Starting wifi controller task I (1536) UI: No locks are active I (1546) UI: keyboardBacklightSet(1, 1) I (1546) UI: Temperature display request ignored I (1546) Display: Setting display brightness to 2 [ ] [ ] [ ] [+] [V] [O] [ ] [-] [O] [ ] I (1546) WiFiController: Reconfiguration of the device I (1546) aws_cloud_handler: Creating Aws cloud handler I (1566) Display: Setting display brightness to 15 I (1586) WiFiController: Configuring the STA W (1596) Config_aws: Not able to get AWS thing name size. Using default thing name [ ] --- [ ] | | | | [ ] [+] [V] [O] --- --- [ ] | | [-] [O] [ ] W (1606) WiFiHandler: STA disconnection called when STA was not configured! I (1616) aws_cloud_handler: Device Mac Address: 08:3A:F2:AB:40:F8 I (1646) aws_cloud_handler: AWS IoT thing name: provisioning-08:3A:F2:AB:40:F8 W (1656) AWS_cmd_hnd: HeatersSettingsHandler, creating new object! I (1646) WiFiHandler: About to connect to network '5b6cb4' with password '244823546' ... W (1666) mqtt_aws_metrics: HeatersMetricsHandler, creating new object I (1676) DiagnosticsHandler: Start to serach flash to get second stage bootloader id I (1676) WiFiController: Scaning wifi APs in progresss I (1686) WiFiHandler: Beginning scanning of access points! I (1546) WiFiLedController: runTask started... I (1696) Misc: startOrStopWebServerAccordingToConfig - type=2, should be started=1 I (1706) phy_init: phy_version 4670,719f9f6,Feb 18 2021,17:07:07 I (1826) WiFiHandler: Sending information about wifi state changed, STA started I (1826) WiFiController: STA started I (1826) WiFiHandler: About to scan available access points! I (1836) LocalHttp: Starting Web server... I (1836) DiagnosticsHandler: String reference is found in flash I (1846) DiagnosticsHandler: Second stage bootloader id is: v4.4.3-dirty I (1846) Main: Application main finished! I (1846) DiagnosticsHandler: Get second stage bootloader id is ended successfully I (1866) CloudSwitcher: monitorForRollback - not needed to run, rollback not required! W (1866) aws_cloud_handler: Cloud handler created by another thread I (1876) CloudSwitcher: connectToNewCloudProviderIfApplicable I (1886) WiFiController: waitUntilConnectedToTheInternet(timeoutMs = -1) I (1896) WiFiController: isConnectedToInternet(-1, 1) I (1886) aws_cloud_handler: waitUntilConnectedToCloud started... I (1906) aws_cloud_handler: Waiting for the cloud connection I (1906) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() I (2006) UI: Temperature display request ignored I (2006) Triac: Group control duty cycle [ 100 ] [ ] --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | [-] [O] [S] I (2516) Display: Setting display brightness to 15 [ ] [--] | | [--] [+] [V] [O] --- [--] | [-] [O] [S] I (2696) WiFiLedController: WiFi Led state changed to 5 [ G] [--] | | [--] [+] [V] [O] --- [--] | [-] [O] [S] [ ] [--] | | [--] [+] [V] [O] --- [--] | [-] [O] [S] I (3516) Display: Setting display brightness to 15 [ ] --- [--] | [--] [+] [V] [O] --- [--] | [-] [O] [S] --- I (4246) WiFiHandler: Finished scanning available access points [ G] --- [--] | [--] [+] [V] [O] --- [--] | [-] [O] [S] --- I (4336) WiFiHandler: SSID | Authmethod | GroupCipher | PairwiseCipher  I (4336) WiFiHandler: 5b6cb4 | 4 | 3 | 5 I (4346) WiFiHandler: CGA2121_RyTXRP4 | 3 | 4 | 4 I (4356) WiFiHandler: WIFI_EVENT_STA_STOP I (4356) WiFiHandler: Sending information about wifi state changed, STA interface stopped I (4366) WiFiHandler: Authmode of WiFi Network: '4'  I (4366) WiFiController: STA_STOP I (4376) WiFiController: STA Interface disabled I (4366) WiFiHandler: Authmode set to: '3'  I (4386) WiFiHandler: Creating net if interface I (4406) WiFiHandler: Starting on channel 0 I (4406) WiFiHandler: Sending information about wifi state changed, WiFi STA configured I (4406) WiFiController: Configured wifi event I (4416) WiFiHandler: STA Initialized! I (4416) WiFiHandler: Sending information about wifi state changed, STA started W (4416) wifi:Haven't to connect to a suitable AP now! I (4426) WiFiController: STA started I (4446) WiFiHandler: Starting mDNS service with hostname: mill_panel_heater_083AF2AB40F8 E (4446) wifi:sta is connecting, return error I (4516) Display: Setting display brightness to 15 [ G] --- [--] | [--] [+] [V] [O] --- [--] | [-] [O] [S] --- [ ] --- [--] | [--] [+] [V] [O] --- [--] | [-] [O] [S] --- W (5426) wifi:Haven't to connect to a suitable AP now! I (5516) Display: Setting display brightness to 15 [ ] [--] | [--] [+] [V] [O] [--] | [-] [O] [S] [ G] [--] | [--] [+] [V] [O] [--] | [-] [O] [S] W (6426) wifi:Haven't to connect to a suitable AP now! I (6516) App: Regulator control loop requested externaly! I (6516) Triac: Group control duty cycle [ 100 ] [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- W (6866) wifi:idx:0 (ifx:0, e8:40:f2:c1:b2:aa), tid:0, ssn:0, winSize:64 I (6896) WiFiHandler: Sending information about wifi state changed, STA connected to AP I (6896) WiFiController: STA connected to AP [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (8946) esp_netif_handlers: sta ip: 192.168.0.16, mask: 255.255.255.0, gw: 192.168.0.1 I (8946) WiFiHandler: Sending information about wifi state changed, IP acquired event I (8946) WiFiController: IP acquired event I (8956) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() I (8976) WiFiController: Set internet connection bit I (8976) WiFiController: isConnectedToInternet(2147483647, 1) I (8976) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() I (8986) WiFiController: Set internet connection bit I (8996) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() E (8996) esp_matter_endpoint: Create thermo cluster I (8996) WiFiLedController: WiFi Led state changed to 6 I (8996) NtpTime: startNtpClient... [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- W (9016) esp_matter_core: Attribute 0x0016 on cluster 0x0201 already exists. Not creating again. I (9016) WiFiController: waitUntilConnectedToTheInternet(timeoutMs = -1) I (9046) MatterThermostatDriver: Thermostat created with endpoint_id 1 I (9056) WiFiController: isConnectedToInternet(-1, 1) E (9066) imp: Crypto init I (9066) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() E (9066) imp: Generic init I (9076) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() E (9086) chip[DL]: Init entropy I (9086) aws_cloud_handler: waitUntilConnectedToCloud started... E (9096) chip[DL]: System layer I (9096) aws_cloud_handler: Waiting for the cloud connection E (9106) chip[DL]: Configuration I (9136) chip[DL]: NVS set: chip-counters/reboot-count = 29 (0x1D) E (9136) chip[DL]: UDP E (9136) chip[DL]: Connectivity E (9146) imp: Sys clock init I (9146) chip[DL]: Real time clock set to 946684800 (0100/00/01 00:00:00 UTC) I (9146) chip[DL]: WiFi station state change: NotConnected -> Connected I (9156) chip[DL]: WiFi station interface connected I (9166) chip[DL]: IPv4 Internet connectivity ESTABLISHED I (9166) chip[DL]: Done driving station state, nothing else to do... I (9176) chip[SVR]: Server initializing... I (9186) chip[TS]: Last Known Good Time: 2023-09-05T10:09:00 I (9186) chip[DMG]: AccessControl: initializing I (9196) chip[DMG]: Examples::AccessControlDelegate::Init I (9196) chip[DMG]: AccessControl: setting I (9206) chip[DMG]: DefaultAclStorage: initializing I (9206) chip[DMG]: DefaultAclStorage: 0 entries loaded I (9236) chip[ZCL]: Using ZAP configuration... I (9236) esp_matter_cluster: Cluster plugin init common callback I (9236) chip[DMG]: AccessControlCluster: initializing I (9246) chip[ZCL]: 0x3f432241ep 0 clus 0x0000_0030 attr 0x0000_0000 not supported I (9256) chip[ZCL]: Initiating Admin Commissioning cluster. E (9256) chip[ZCL]: Duplicate attribute override registration failed E (9266) coms: TIMEOUT E (9266) coms: disarmed E (9276) coms: update E (9276) coms: timer E (9276) coms: pase E (9286) coms: exchange E (9286) coms: GetCommissionableDataProvider E (9286) coms: GetSpake2pIterationCount E (9296) coms: GetSpake2pSalt E (9296) coms: GetSpake2pVerifier E (9306) coms: kSpake2p_VerifierSerialized_Length E (9306) coms: serializedVerifierLen E (9316) coms: Deserialize E (9316) coms: WaitForPairing E (9316) coms: StartAdvertisement I (9326) chip[DIS]: Updating services using commissioning mode 1 I (9336) chip[DIS]: CHIP minimal mDNS started advertising. I (9336) chip[DIS]: Advertise commission parameter vendorID=65521 productID=32768 discriminator=3840/15 cm=1 I (9346) chip[DIS]: CHIP minimal mDNS configured as 'Commissionable node device'; instance name: A3CEE3E23C2724DB. I (9366) chip[DIS]: mDNS service published: _matterc._udp I (9366) chip[IN]: CASE Server enabling CASE session setups I (9376) chip[SVR]: Joining Multicast groups I (9376) chip[SVR]: Server Listening... I (9376) esp_matter_core: Dynamic endpoint 0 added I (9386) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0003's Attribute 0x0001 is 0 ********** I (9396) MatterThermostatDriver: matterAttributeUpdate: PRE_UPDATE I (9406) MatterThermostatDriver: ENDPOINT = 1, attributes 1 I (9406) MatterThermostatDriver: CAllback received POST_UPDATE I (9416) esp_matter_core: Dynamic endpoint 1 added E (9426) chip[DL]: Long dispatch time: 249 ms, for event type 2 I (9426) chip[DL]: Updating advertising data I (9436) MatterThermostatDriver: New matter callback 32768 I (9436) MatterThermostatDriver: New matter callback 32770 I (9446) MatterThermostatDriver: New matter callback 53250 I (9446) MatterThermostatDriver: Commissioning window opened E (9456) MatterThermostatDriver: Set defaults E (9466) MatterThermostatDriver: updateLocalTemperature  E (9466) MatterThermostatDriver: Update set point id = 0 I (9476) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0000 is 22 ********** I (9486) MatterThermostatDriver: matterAttributeUpdate: PRE_UPDATE I (9496) MatterThermostatDriver: ENDPOINT = 1, attributes 0 E (9496) MatterThermostatDriver: Unknown id in matterAttributeUpdate: 0 I (9506) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0xFFFC is 1 ********** I (9516) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0003 is 1000 ********** I (9526) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0004 is 3500 ********** I (9536) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0015 is 1000 ********** I (9546) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0016 is 3500 ********** I (9556) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0012 is 1817 ********** I (9566) MatterThermostatDriver: CAllback received POST_UPDATE E (9576) MatterThermostatDriver: Update set point id = 18 I (9586) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0012 is 1817 ********** I (9596) MatterThermostatDriver: matterAttributeUpdate: PRE_UPDATE I (9596) MatterThermostatDriver: ENDPOINT = 1, attributes 18 I (9606) MatterThermostatDriver: Updating setpoint from matterAttributeUpdate I (9616) MatterThermostatDriver: MatterThermostatDriver::onSetSetpoint called with val: 1817 E (9626) MatterThermostatDriver: updateLocalTemperature  E (9626) MatterThermostatDriver: Update set point id = 0 I (9636) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0000 is 1817 ********** I (9646) MatterThermostatDriver: matterAttributeUpdate: PRE_UPDATE I (9656) MatterThermostatDriver: ENDPOINT = 1, attributes 0 E (9656) MatterThermostatDriver: Unknown id in matterAttributeUpdate: 0 I (9666) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0xFFFC is 1 ********** I (9676) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0003 is 1000 ********** I (9686) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0004 is 3500 ********** I (9696) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0015 is 1000 ********** I (9706) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0016 is 3500 ********** I (9716) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0012 is 1817 ********** I (9736) MatterThermostatDriver: CAllback received POST_UPDATE I (9736) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0xFFFC is 1 ********** I (9746) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0003 is 1000 ********** I (9756) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0004 is 3500 ********** I (9766) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0015 is 1000 ********** I (9776) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0016 is 3500 ********** I (9796) esp_matter_attribute: ********** Endpoint 0x0001's Cluster 0x0201's Attribute 0x0012 is 1817 ********** I (9806) MatterThermostatDriver: CAllback received POST_UPDATE I (9806) WiFiController: updateLastInternetConnectionSuccesfulTimestamp() I (9816) WiFiController: Internet connection tested can connect to the internet I (10516) NtpTime: sntp_sync_time Time synchronized - 1693901562 sec W (10516) NtpTime: Setting utc timestamp of device startup in us 1693901551649714 W (10526) NtpTime: Setting utc timestamp of device startup in ms 1693901551650 [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (10916) aws_cloud_handler: Waiting for the cloud connection [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (11506) Triac: Group control duty cycle [ 100 ] [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (16496) Triac: Group control duty cycle [ 100 ] [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (18106) aws_cloud_handler: Waiting for the cloud connection [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (19916) aws_cloud_handler: Waiting for the cloud connection [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (21486) Triac: Group control duty cycle [ 100 ] [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- [ G] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (26476) Triac: Group control duty cycle [ 100 ] [ ] --- --- --- [--] | | | | [--] [+] [V] [O] --- --- [--] | | | | [-] [O] [S] --- --- o --- I (27106) aws_cloud_handler: Waiting for the cloud connection ESP32 Running idf_monitor in directory /home/damian/Projects/mill/mill-esp32 Executing "/home/damian/.espressif/python_env/idf4.4_py3.8_env/bin/python /home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/tools/idf_monitor.py -p /dev/ttyUSB0 -b 115200 --toolchain-prefix xtensa-esp32-elf- --target esp32 --revision 0 /home/damian/Projects/mill/build_mill_esp32/light.elf -m '/home/damian/.espressif/python_env/idf4.4_py3.8_env/bin/python' '/home/damian/Projects/mill/mill-esp32/main/externals/esp-idf/tools/idf.py' '-B' '.' '-C' './../mill-esp32/' '-DMILL_PRODUCT_TYPE=H3P' '-DBUILD_WITH_PRINTS_AND_LOGS=ON' '-DBUILD_IN_DEBUG_MODE=OFF' '-DUSE_CONSOLE=OFF' '-DUSE_SIMULATED_DISPLAY=ON' '-DUSE_REAL_DISPLAY=OFF' '-DUSE_HIGH_VOLTAGE=OFF' '-DUSE_SIMULATED_SENSORS=ON' '-DUSE_REAL_SENSORS=OFF' '-DDEFAULT_CLOUD_PROVIDER=AWS' '-DCLOUD_ENV=DEV'"... Done