Timed out waiting for readiness from PN532!

Hi everyone,

I’m very new to this, so sorry in advance if this is an easy fix. I am trying to make an NFC tag reader (GitHub - adonno/tagreader: Simple to build/use NFC tag reader, specially created for Home Assistant) for my kids, but I’m having a lot of issues. This topic will be focused on the PN532 issue I am receiving.

I am flashing my device using Home Assistant ESPHome add on. I choose to install using the USB drive on my server (I am using proxmox with HA container - I passed the USB device to this container).

From the very verbose logs, it looks like this may be an issue:

[07:40:08][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:08][V][pn532:300]: Sending ACK for abort
[07:40:08][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:08][V][pn532:352]: Turning RF field OFF
[07:40:08][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:08][V][pn532:284]: Reading ACK...
[07:40:08][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:08][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:08][V][pn532:295]: ACK valid: YES
[07:40:09][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=1010 (now=2010)
[07:40:09][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:09][V][pn532:284]: Reading ACK...
[07:40:09][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:09][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:09][V][pn532:295]: ACK valid: YES
[07:40:09][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:09][VV][i2c.arduino:117]: 0x24 RX 00

here are the full logs, in case something is going on elsewhere (also, I know my wifi is also not working properly, but this is a separate issue non related - I think…).

INFO ESPHome 2023.12.9
INFO Reading configuration /config/esphome/tagreaderv4.yaml...
INFO Generating C++ source...
INFO Compiling app...
Processing tagreaderv2 (board: d1_mini; framework: arduino; platform: platformio/[email protected])
--------------------------------------------------------------------------------
HARDWARE: ESP8266 80MHz, 80KB RAM, 4MB Flash
Dependency Graph
|-- ESPAsyncTCP-esphome @ 2.0.0
|-- ESPAsyncWebServer-esphome @ 3.1.0
|-- DNSServer @ 1.1.1
|-- ESP8266WiFi @ 1.0
|-- ESP8266mDNS @ 1.2
|-- Wire @ 1.0
|-- Improv @ 1.2.3
|-- NeoPixelBus @ 2.7.3
Compiling .pioenvs/tagreaderv2/src/main.cpp.o
Linking .pioenvs/tagreaderv2/firmware.elf
RAM:   [=====     ]  52.4% (used 42944 bytes from 81920 bytes)
Flash: [=====     ]  52.0% (used 543341 bytes from 1044464 bytes)
Building .pioenvs/tagreaderv2/firmware.bin
esp8266_copy_factory_bin([".pioenvs/tagreaderv2/firmware.bin"], [".pioenvs/tagreaderv2/firmware.elf"])
========================= [SUCCESS] Took 19.48 seconds =========================
INFO Successfully compiled program.
esptool.py v4.6.2
Serial port /dev/ttyUSB0
Connecting....
Chip is ESP8266EX
Features: WiFi
Crystal is 26MHz
MAC: c8:c9:a3:33:b0:c6
Uploading stub...
Running stub...
Stub running...
Changing baud rate to 460800
Changed.
Configuring flash size...
Auto-detected Flash size: 4MB
Flash will be erased from 0x00000000 to 0x00085fff...
Compressed 547488 bytes to 368692...
Wrote 547488 bytes (368692 compressed) at 0x00000000 in 8.9 seconds (effective 494.4 kbit/s)...
Hash of data verified.

Leaving...
Hard resetting via RTS pin...
INFO Successfully uploaded program.
INFO Starting log output from /dev/ttyUSB0 with baud rate 115200
[07:40:07]rl l\x90r$\xe2n\xc4l\xc7b|\x8e\x90 \x92rb\xc4b\xf2nnlnnbbp\x8c$blrlp\xf2n\x82\x90l\x8cbn\xe2n\xc7\xc4b\xc4\xf2nn'l\x8cl`\x90nn$`nr\x8e\x92\x92nrr\x92`p\xf2n\x82r\x8cbbn\xc2nb\xc4\xf2nn'l`\x90nn$`nr\x8e\x92\x92nrl`r\x92\x92nrl`\x9cl$l`\xfc\x82n\x92`
[07:40:07]SDK:2.2.2-dev(38a443e)/Core:3.0.2=30002000/lwIP:STABLE-2_1_2_RELEASE/glue:1.2-48-g7421258/BearSSL:6105635
[07:40:07][I][logger:351]: Log initialized
[07:40:07][C][ota:473]: There have been 0 suspected unsuccessful boot attempts.
[07:40:07][I][app:029]: Running through setup()...
[07:40:07][V][app:030]: Sorting components by setup priority...
[07:40:07][I][i2c.arduino:183]: Performing I2C bus recovery
[07:40:07][VV][scheduler:063]: set_interval(name='', interval=60000, offset=2780)
[07:40:07][C][esp8266_pwm:017]: Setting up ESP8266 PWM Output...
[07:40:07][VV][scheduler:063]: set_interval(name='', interval=5000, offset=2282)
[07:40:07][C][light:035]: Setting up light 'TagReaderv2 LED'...
[07:40:07][D][light:036]: 'TagReaderv2 LED' Setting:
[07:40:07][D][light:041]:   Color mode: RGB
[07:40:07][D][light:085]:   Transition length: 1.0s
[07:40:07][D][template.switch:046]:   Restored state ON
[07:40:07][D][switch:012]: 'TagReaderv2 Buzzer Enabled' Turning ON.
[07:40:07][D][switch:055]: 'TagReaderv2 Buzzer Enabled': Sending state ON
[07:40:07][D][template.switch:046]:   Restored state ON
[07:40:07][D][switch:012]: 'TagReaderv2 LED enabled' Turning ON.
[07:40:07][D][switch:055]: 'TagReaderv2 LED enabled': Sending state ON
[07:40:07][C][pn532:018]: Setting up PN532...
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FF02FED4022A00
[07:40:07][V][pn532:284]: Reading ACK...
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:07][V][pn532:295]: ACK valid: YES
[07:40:07][V][pn532_i2c:040]: Reading response
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF06FAD5
[07:40:07][V][pn532:305]: Sending NACK for retransmit
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FFFF0000
[07:40:07][V][pn532_i2c:046]: Reading response of length 5
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF06FAD50332010607E800
[07:40:07][D][pn532:036]: Found chip PN532
[07:40:07][D][pn532:037]: Firmware ver. 1.6
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FF05FBD4140114010200
[07:40:07][V][pn532:284]: Reading ACK...
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:07][V][pn532:295]: ACK valid: YES
[07:40:07][V][pn532_i2c:040]: Reading response
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF02FED5
[07:40:07][V][pn532:305]: Sending NACK for retransmit
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FFFF0000
[07:40:07][V][pn532_i2c:046]: Reading response of length 1
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF02FED5151600
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FF05FBD4140114010200
[07:40:07][V][pn532:284]: Reading ACK...
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:07][V][pn532:295]: ACK valid: YES
[07:40:07][V][pn532_i2c:040]: Reading response
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF02FED5
[07:40:07][V][pn532:305]: Sending NACK for retransmit
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FFFF0000
[07:40:07][V][pn532_i2c:046]: Reading response of length 1
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF02FED5151600
[07:40:07][V][pn532:352]: Turning RF field OFF
[07:40:07][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:07][V][pn532:284]: Reading ACK...
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:07][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:07][V][pn532:295]: ACK valid: YES
[07:40:07][VV][scheduler:063]: set_interval(name='update', interval=1000, offset=434)
[07:40:07][D][binary_sensor:034]: 'TagReaderv2 Status': Sending initial state OFF
[07:40:07][V][text_sensor:013]: 'TagReaderv2 ESPHome Version': Received new state 2023.12.9
[07:40:07][D][text_sensor:064]: 'TagReaderv2 ESPHome Version': Sending state '2023.12.9'
[07:40:07][C][wifi:038]: Setting up WiFi...
[07:40:08][C][wifi:051]: Starting WiFi...
[07:40:08][C][wifi:052]:   Local MAC: C8:C9:A3:33:B0:C6
[07:40:08][V][wifi_esp8266:059]: Enabling STA.
[07:40:08]mode : sta(c8:c9:a3:33:b0:c6)
[07:40:08]add if0
[07:40:08][V][wifi_esp8266:548]: Event: Changed Mode old=OFF new=STA
[07:40:08]wifi evt: 8
[07:40:08]nul mode, fpm auto sleep set:enalbe
[07:40:08]sleep disable
[07:40:08][D][wifi:455]: Starting scan...
[07:40:08][VV][scheduler:225]: Running interval '' with interval=60000 last_execution=4294904627 (now=512)
[07:40:08][VV][scheduler:225]: Running interval '' with interval=5000 last_execution=4294960138 (now=512)
[07:40:08][VV][light.addressable:015]: Addressable Light 'TagReaderv2 LED' (effect_active=NO)
[07:40:08][VV][light.addressable:018]:   [ 0] Color: R=  0 G=  0 B=  0 W=  0
[07:40:08][VV][light.addressable:021]:  
[07:40:08][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=4294966306 (now=512)
[07:40:08][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:08][V][pn532:284]: Reading ACK...
[07:40:08][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:08][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:08][V][pn532:295]: ACK valid: YES
[07:40:08][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:10][VV][light.addressable:015]: Addressable Light 'TagReaderv2 LED' (effect_active=NO)
[07:40:10][VV][light.addressable:018]:   [ 0] Color: R=  0 G=  0 B=  0 W=  0
[07:40:10][VV][light.addressable:021]:  
[07:40:10][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=2010 (now=3010)
[07:40:10][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:10][V][pn532:284]: Reading ACK...
[07:40:10][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:10][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:10][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:10][V][pn532:300]: Sending ACK for abort
[07:40:10][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:10][V][pn532:352]: Turning RF field OFF
[07:40:10][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:10][V][pn532:284]: Reading ACK...
[07:40:10][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:10][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:10][V][pn532:295]: ACK valid: YES
[07:40:11][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=3010 (now=4010)
[07:40:11][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:11][V][pn532:284]: Reading ACK...
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:11][V][pn532:295]: ACK valid: YES
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:11][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:11][V][pn532:300]: Sending ACK for abort
[07:40:11][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:11][V][pn532:352]: Turning RF field OFF
[07:40:11][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:11][V][pn532:284]: Reading ACK...
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:11][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:11][V][pn532:295]: ACK valid: YES
[07:40:12][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=4010 (now=5010)
[07:40:12][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:12][V][pn532:284]: Reading ACK...
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:12][V][pn532:295]: ACK valid: YES
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:12][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:12][V][pn532:300]: Sending ACK for abort
[07:40:12][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:12][V][pn532:352]: Turning RF field OFF
[07:40:12][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:12][V][pn532:284]: Reading ACK...
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:12][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:12][V][pn532:295]: ACK valid: YES
[07:40:13][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=5010 (now=6010)
[07:40:13][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:13][V][pn532:284]: Reading ACK...
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:13][V][pn532:295]: ACK valid: YES
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:13][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:13][V][pn532:300]: Sending ACK for abort
[07:40:13][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:13][V][pn532:352]: Turning RF field OFF
[07:40:13][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:13][V][pn532:284]: Reading ACK...
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:13][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:13][V][pn532:295]: ACK valid: YES
[07:40:13]scandone
[07:40:13][D][wifi:470]: Found networks:
[07:40:13][I][wifi:513]: - [redacted]▂▄▆█
[07:40:13][D][wifi:515]:     Channel: 6
[07:40:13][D][wifi:516]:     RSSI: -41 dB
[07:40:13][D][wifi:518]: - 'DIRECT-D0-HP OfficeJet 8010'[redacted] (E2:73:E7:76:9A:D0) [redacted]▂▄▆█
[07:40:13][D][wifi:518]: - 'DIRECT-34-HP OfficeJet 3830'[redacted] (00:68:EB:17:BF:35) [redacted]▂▄▆█
[07:40:13][D][wifi:518]: - ''[redacted] (92:5A:B0:3D:DB:8D) [redacted]▂▄▆█
[07:40:13][D][wifi:518]: - 'Mon Doc'[redacted] (92:5A:B0:3D:DB:8C) [redacted]▂▄▆█
[07:40:14][D][wifi:518]: - 'BELL244'[redacted] (C0:3C:04:09:ED:0A) [redacted]▂▄▆█
[07:40:14][I][wifi:300]: WiFi Connecting to 'BELL680'...
[07:40:14][V][wifi:302]: Connection Params:
[07:40:14][V][wifi:303]:   SSID: [redacted]
[07:40:14][V][wifi:306]:   BSSID: [redacted]
[07:40:14][V][wifi:326]:   Password: [redacted]
[07:40:14][V][wifi:331]:   Channel: 6
[07:40:14][V][wifi:337]:   Manual IP: Static IP=192.168.2.59 Gateway=192.168.2.1 Subnet=255.255.255.0 DNS1=8.8.4.4 DNS2=8.8.8.8
[07:40:14][V][wifi:342]:   Hidden: NO
[07:40:14][V][wifi_esp8266:506]: Event: Changed AuthMode old=OPEN new=WPA2 PSK
[07:40:14]wifi evt: 2
[07:40:14]scandone
[07:40:15]state: 0 -> 2 (b0)
[07:40:15][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=6010 (now=7839)
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:15][V][pn532:284]: Reading ACK...
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:15][V][pn532:295]: Astate: 2 -> 3 (0)
[07:40:15]CK valid: YES
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15]state: 3 -> 5 (10)
[07:40:15]add 0
[07:40:15]aid 35
[07:40:15]cnt 
[07:40:15][VV][scheduler:225]: Running interval '' with interval=5000 last_execution=2842 (now=7870)
[07:40:15][VV][light.addressable:015]: Addressable Light 'TagReaderv2 LED' (effect_active=NO)
[07:40:15][VV][light.addressable:018]:   [ 0] Color: R=  0 G=  0 B=  0 W=  0
[07:40:15][VV][light.addressable:021]:  
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15]
[07:40:15]connected with BELL680, channel 6
[07:40:15]ip:192.168.2.59,mask:255.255.255.0,gw:192.168.2.1
[07:40:15]ip:192.168.2.59,mask:255.255.255.0,gw:192.168.2.1
[07:40:15][V][wifi_esp8266:482]: Event: Connected ssid='BELL680' bssid=0C:AC:8A:45:73:8F channel=6
[07:40:15]wifi evt: 0
[07:40:15][V][wifi_esp8266:521]: Event: Got IP static_ip=192.168.2.59 gateway=192.168.2.1 netmask=255.255.255.0
[07:40:15]wifi evt: 3
[07:40:15][I][wifi:587]: WiFi Connected!
[07:40:15][C][wifi:405]:   Local MAC: C8:C9:A3:33:B0:C6
[07:40:15][C][wifi:410]:   SSID: 'BELL680'[redacted]
[07:40:15][C][wifi:411]:   IP Address: 192.168.2.59
[07:40:15][C][wifi:412]:   BSSID: 0C:AC:8A:45:73:8F[redacted]
[07:40:15][C][wifi:414]:   Hostname: 'tagreaderv2-33b0c6'
[07:40:15][C][wifi:416]:   Signal strength: -18 dB ▂▄▆█
[07:40:15][V][wifi:418]:   Priority: 0.0
[07:40:15][C][wifi:420]:   Channel: 6
[07:40:15][C][wifi:421]:   Subnet: 255.255.255.0
[07:40:15][C][wifi:422]:   Gateway: 192.168.2.1
[07:40:15][C][wifi:423]:   DNS1: 8.8.4.4
[07:40:15][C][wifi:424]:   DNS2: 8.8.8.8
[07:40:15][D][wifi:596]: Disabling AP...
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:15][V][pn532:300]: Sending ACK for abort
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:15][V][pn532:352]: Turning RF field OFF
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:15][V][pn532:284]: Reading ACK...
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:15][V][pn532:295]: ACK valid: YES
[07:40:15][C][ota:097]: Over-The-Air Updates:
[07:40:15][C][ota:098]:   Address: 192.168.2.59:8266
[07:40:15][C][api:025]: Setting up Home Assistant API server...
[07:40:15][VV][scheduler:063]: set_interval(name='update', interval=1000, offset=270)
[07:40:15][VV][scheduler:063]: set_interval(name='update', interval=1000, offset=22)
[07:40:15][I][app:062]: setup() finished successfully!
[07:40:15][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=6788 (now=8077)
[07:40:15][V][text_sensor:013]: 'TagReaderv2 IP Address': Received new state 192.168.2.59
[07:40:15][D][text_sensor:064]: 'TagReaderv2 IP Address': Sending state '192.168.2.59'
[07:40:15][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=7010 (now=8077)
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:15][V][pn532:284]: Reading ACK...
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:15][V][pn532:295]: ACK valid: YES
[07:40:15][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=7043 (now=8077)
[07:40:15][V][text_sensor:013]: 'TagReaderv2 Connected SSID': Received new state BELL680
[07:40:15][D][text_sensor:064]: 'TagReaderv2 Connected SSID': Sending state 'BELL680'
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15][I][app:102]: ESPHome version 2023.12.9 compiled on Feb  3 2024, 07:39:39
[07:40:15][I][app:104]: Project adonno.tag_reader version 1.4
[07:40:15][C][wifi:573]: WiFi:
[07:40:15][C][wifi:405]:   Local MAC: C8:C9:A3:33:B0:C6
[07:40:15][C][wifi:410]:   SSID: 'BELL680'[redacted]
[07:40:15][C][wifi:411]:   IP Address: 192.168.2.59
[07:40:15][C][wifi:412]:   BSSID: 0C:AC:8A:45:73:8F[redacted]
[07:40:15][C][wifi:414]:   Hostname: 'tagreaderv2-33b0c6'
[07:40:15][C][wifi:416]:   Signal strength: -21 dB ▂▄▆█
[07:40:15][V][wifi:418]:   Priority: 0.0
[07:40:15][C][wifi:420]:   Channel: 6
[07:40:15][C][wifi:421]:   Subnet: 255.255.255.0
[07:40:15][C][wifi:422]:   Gateway: 192.168.2.1
[07:40:15][C][wifi:423]:   DNS1: 8.8.4.4
[07:40:15][C][wifi:424]:   DNS2: 8.8.8.8
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15][C][logger:439]: Logger:
[07:40:15][C][logger:440]:   Level: VERY_VERBOSE
[07:40:15][C][logger:441]:   Log Baud Rate: 115200
[07:40:15][C][logger:443]:   Hardware UART: UART0
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:15][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:15][V][pn532:300]: Sending ACK for abort
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:15][V][pn532:352]: Turning RF field OFF
[07:40:15][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:15][V][pn532:284]: Reading ACK...
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:15][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:15][V][pn532:295]: ACK valid: YES
[07:40:15][C][i2c.arduino:053]: I2C Bus:
[07:40:15][C][i2c.arduino:054]:   SDA Pin: GPIO4
[07:40:15][C][i2c.arduino:055]:   SCL Pin: GPIO5
[07:40:15][C][i2c.arduino:056]:   Frequency: 400000 Hz
[07:40:15][C][i2c.arduino:059]:   Recovery: bus successfully recovered
[07:40:15][C][esp8266_pwm:022]: ESP8266 PWM:
[07:40:15][C][esp8266_pwm:023]:   Pin: GPIO13
[07:40:15][C][esp8266_pwm:024]:   Frequency: 1000.0 Hz
[07:40:15][C][light:103]: Light 'TagReaderv2 LED'
[07:40:15][C][light:105]:   Default Transition Length: 1.0s
[07:40:15][C][light:106]:   Gamma Correct: 2.80
[07:40:15][C][template.switch:068]: Template Switch 'TagReaderv2 Buzzer Enabled'
[07:40:15][C][template.switch:070]:   Icon: 'mdi:volume-high'
[07:40:15][C][template.switch:090]:   Restore Mode: restore defaults to ON
[07:40:15][C][template.switch:057]:   Optimistic: YES
[07:40:15][C][template.switch:068]: Template Switch 'TagReaderv2 LED enabled'
[07:40:15][C][template.switch:070]:   Icon: 'mdi:alarm-light-outline'
[07:40:15][C][template.switch:090]:   Restore Mode: restore defaults to ON
[07:40:15][C][template.switch:057]:   Optimistic: YES
[07:40:15][C][restart.button:017]: Restart Button 'TagReaderv2 Restart'
[07:40:15][C][pn532:431]: PN532:
[07:40:15][C][pn532:443]:   Update Interval: 1.0s
[07:40:15][C][pn532_i2c:125]:   Address: 0x24
[07:40:16][C][status:034]: Status Binary Sensor 'TagReaderv2 Status'
[07:40:16][C][status:034]:   Device Class: 'connectivity'
[07:40:16][C][version.text_sensor:021]: Version Text Sensor 'TagReaderv2 ESPHome Version'
[07:40:16][C][version.text_sensor:021]:   Icon: 'mdi:new-box'
[07:40:16][V][version.text_sensor:021]:   Unique ID: 'c8c9a333b0c6-version'
[07:40:16][C][rtttl:029]: Rtttl
[07:40:16][C][captive_portal:088]: Captive Portal:
[07:40:16][C][mdns:115]: mDNS:
[07:40:16][C][mdns:116]:   Hostname: tagreaderv2-33b0c6
[07:40:16][V][mdns:117]:   Services:
[07:40:16][V][mdns:119]:   - _esphomelib, _tcp, 6053
[07:40:16][V][mdns:121]:     TXT: version = 2023.12.9
[07:40:16][V][mdns:121]:     TXT: mac = c8c9a333b0c6
[07:40:16][V][mdns:121]:     TXT: platform = ESP8266
[07:40:16][V][mdns:121]:     TXT: board = d1_mini
[07:40:16][V][mdns:121]:     TXT: network = wifi
[07:40:16][V][mdns:121]:     TXT: project_name = adonno.tag_reader
[07:40:16][V][mdns:121]:     TXT: project_version = 1.4
[07:40:16][V][mdns:121]:     TXT: package_import_url = github://adonno/tagreader/tagreader.yaml
[07:40:16][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=7788 (now=8823)
[07:40:16][C][ota:097]: Over-The-Air Updates:
[07:40:16][C][ota:098]:   Address: 192.168.2.59:8266
[07:40:16][C][api:139]: API Server:
[07:40:16][C][api:140]:   Address: 192.168.2.59:6053
[07:40:16][C][api:144]:   Using noise encryption: NO
[07:40:16][C][improv_serial:032]: Improv Serial:
[07:40:16][C][wifi_info:009]: WifiInfo IPAddress 'TagReaderv2 IP Address'
[07:40:16][C][wifi_info:009]:   Icon: 'mdi:wifi'
[07:40:16][V][wifi_info:009]:   Unique ID: 'c8c9a333b0c6-wifiinfo-ip'
[07:40:16][C][wifi_info:011]: WifiInfo SSID 'TagReaderv2 Connected SSID'
[07:40:16][C][wifi_info:011]:   Icon: 'mdi:wifi-strength-2'
[07:40:16][V][wifi_info:011]:   Unique ID: 'c8c9a333b0c6-wifiinfo-ssid'
[07:40:16][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=8010 (now=9011)
[07:40:16][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:16][V][pn532:284]: Reading ACK...
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:16][V][pn532:295]: ACK valid: YES
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=8043 (now=9044)
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:16][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:16][V][pn532:300]: Sending ACK for abort
[07:40:16][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:16][V][pn532:352]: Turning RF field OFF
[07:40:16][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:16][V][pn532:284]: Reading ACK...
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:16][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:16][V][pn532:295]: ACK valid: YES
[07:40:17][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=8788 (now=9788)
[07:40:17][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=9010 (now=10012)
[07:40:17][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:17][V][pn532:284]: Reading ACK...
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:17][V][pn532:295]: ACK valid: YES
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=9043 (now=10046)
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:17][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:17][V][pn532:300]: Sending ACK for abort
[07:40:17][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:17][V][pn532:352]: Turning RF field OFF
[07:40:17][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:17][V][pn532:284]: Reading ACK...
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:17][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:17][V][pn532:295]: ACK valid: YES
[07:40:18][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=9788 (now=10794)
[07:40:18][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=10010 (now=11011)
[07:40:18][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:18][V][pn532:284]: Reading ACK...
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:18][V][pn532:295]: ACK valid: YES
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=10043 (now=11045)
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:18][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:18][V][pn532:300]: Sending ACK for abort
[07:40:18][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:18][V][pn532:352]: Turning RF field OFF
[07:40:18][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:18][V][pn532:284]: Reading ACK...
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:18][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:18][V][pn532:295]: ACK valid: YES
[07:40:19][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=10788 (now=11791)
[07:40:19][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=11010 (now=12010)
[07:40:19][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:19][V][pn532:284]: Reading ACK...
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:19][V][pn532:295]: ACK valid: YES
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=11043 (now=12044)
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:19][V][pn532:330]: Timed out waiting for readiness from PN532!
[07:40:19][V][pn532:300]: Sending ACK for abort
[07:40:19][VV][i2c.arduino:141]: 0x24 TX 0000FF00FF00
[07:40:19][V][pn532:352]: Turning RF field OFF
[07:40:19][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD4320100F900
[07:40:19][V][pn532:284]: Reading ACK...
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:19][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:19][V][pn532:295]: ACK valid: YES
[07:40:20][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=11788 (now=12790)
[07:40:20][VV][scheduler:225]: Running interval '' with interval=5000 last_execution=7842 (now=12842)
[07:40:20][VV][light.addressable:015]: Addressable Light 'TagReaderv2 LED' (effect_active=NO)
[07:40:20][VV][light.addressable:018]:   [ 0] Color: R=  0 G=  0 B=  0 W=  0
[07:40:20][VV][light.addressable:021]:  
[07:40:20][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=12010 (now=13015)
[07:40:20][VV][i2c.arduino:141]: 0x24 TX 0000FF04FCD44A0100E100
[07:40:20][V][pn532:284]: Reading ACK...
[07:40:20][VV][i2c.arduino:117]: 0x24 RX 01
[07:40:20][VV][i2c.arduino:117]: 0x24 RX 010000FF00FF00
[07:40:20][V][pn532:295]: ACK valid: YES
[07:40:20][VV][i2c.arduino:117]: 0x24 RX 00
[07:40:20][VV][scheduler:225]: Running interval 'update' with interval=1000 last_execution=12043 (now=13049)

Anyone know what may be going on? I tried resoldering my components many times thinking it may be a faulty connection, but no luck.

Could be this issue?

You could try this:

Reducing the bus frequency to 10kHz helps a little, but does not solve the problem.

But looks like you need the new I2C feature request:

Changing the timeout to 1000µs (probably less) solves the issue.