search

andyboeh / esphome-elero

An ESPHOME custom component to control Elero blinds using the bidirectional Elero protocol
22 stars 6 forks source link

Blinds sometimes not moving #5

Open peterjuras opened 2 months ago

peterjuras commented 2 months ago

Hi!

First of all thank you for creating this great component, I really appreciate it! 🙏

I'm using your component with three elero blinds. I have automations that simultaneously close & open three blinds when the sun goes down & up, but I noticed that sometimes one of the blinds will not move.

It also doesn't move when I manually & repeatedly send the command again a few times after a couple of seconds. If I wait a couple of minutes it typically works again.

Do you know if there is an issue with simultaneous control of blinds? Should I wait a certain time between sending commands or do you have other recommendations? I don't think it is a range issue, since it works most of the time and it is the "middle" blind and even closest to my ESP32.

Thanks, Peter

andyboeh commented 2 months ago

Actually, I'm seeing the same issue on the single blind that I control automatically. I thought it was due to the distance, I cannot get a direct connection to the blind. Do you get any errors in the log?

peterjuras commented 2 months ago

I haven't noticed any errors in my logs last time I checked, but I'll try to monitor for logs again if it happens again.

Do you also control multiple blinds at the same time?

andyboeh commented 2 months ago

No, not at this time, it's likely that there are some problems here.

peterjuras commented 2 months ago

I'll check today to see if there are any logs during a simultaneous send of closing covers 👍

peterjuras commented 2 months ago

I checked for logs today. All three blinds did move successfully, however there were still a couple of error messages in the logs:

INFO ESPHome 2024.6.6
INFO Reading configuration /config/esphome/living-room-elero-transmitter.yaml...
WARNING GPIO5 is a strapping PIN and should only be used for I/O with care.
Attaching external pullup/down resistors to strapping pins can cause unexpected failures.
See https://esphome.io/guides/faq.html#why-am-i-getting-a-warning-about-strapping-pins
INFO Starting log output from 192.168.68.72 using esphome API
INFO Successfully connected to living-room-elero-transmitter @ 192.168.68.72 in 0.023s
INFO Successful handshake with living-room-elero-transmitter @ 192.168.68.72 in 0.088s
[21:44:50][I][app:100]: ESPHome version 2024.6.6 compiled on Jul  7 2024, 03:10:59
[21:44:50][C][wifi:599]: WiFi:
[21:44:50][C][wifi:427]:   Local MAC: D4:D4:DA:E3:C4:E0
[21:44:50][C][wifi:432]:   SSID: 'Rutzheim'[redacted]
[21:44:50][C][wifi:435]:   IP Address: 192.168.68.72
[21:44:50][C][wifi:439]:   BSSID: 96:53:22:82:E3:10[redacted]
[21:44:50][C][wifi:440]:   Hostname: 'living-room-elero-transmitter'
[21:44:50][C][wifi:442]:   Signal strength: -81 dB ▂▄▆█
[21:44:50][C][wifi:446]:   Channel: 3
[21:44:50][C][wifi:447]:   Subnet: 255.255.252.0
[21:44:50][C][wifi:448]:   Gateway: 192.168.68.1
[21:44:50][C][wifi:449]:   DNS1: 1.1.1.1
[21:44:50][C][wifi:450]:   DNS2: 8.8.8.8
[21:44:50][C][logger:185]: Logger:
[21:44:50][C][logger:186]:   Level: DEBUG
[21:44:50][C][logger:188]:   Log Baud Rate: 115200
[21:44:50][C][logger:189]:   Hardware UART: UART0
[21:44:50][C][spi:068]: SPI bus:
[21:44:50][C][spi:069]:   CLK Pin: GPIO18
[21:44:50][C][spi:070]:   SDI Pin: GPIO19
[21:44:50][C][spi:071]:   SDO Pin: GPIO23
[21:44:50][C][spi:076]:   Using HW SPI: SPI2_HOST
[21:44:50][C][elero:046]: Elero Config: 
[21:44:50][C][elero.cover:012]: Elero Cover 'Living room balcony door'
[21:44:50][C][elero.cover:012]:   Assumed State: YES
[21:44:50][C][elero.cover:012]: Elero Cover 'Living room window'
[21:44:50][C][elero.cover:012]:   Assumed State: YES
[21:44:50][C][elero.cover:012]: Elero Cover 'Dining room'
[21:44:50][C][elero.cover:012]:   Assumed State: YES
[21:44:50][C][mdns:115]: mDNS:
[21:44:50][C][mdns:116]:   Hostname: living-room-elero-transmitter
[21:44:50][C][esphome.ota:073]: Over-The-Air updates:
[21:44:50][C][esphome.ota:074]:   Address: living-room-elero-transmitter.local:3232
[21:44:50][C][esphome.ota:075]:   Version: 2
[21:44:50][C][esphome.ota:078]:   Password configured
[21:44:50][C][safe_mode:018]: Safe Mode:
[21:44:50][C][safe_mode:020]:   Boot considered successful after 60 seconds
[21:44:50][C][safe_mode:021]:   Invoke after 10 boot attempts
[21:44:50][C][safe_mode:023]:   Remain in safe mode for 300 seconds
[21:44:50][C][api:139]: API Server:
[21:44:50][C][api:140]:   Address: living-room-elero-transmitter.local:6053
[21:44:50][C][api:142]:   Using noise encryption: YES
[21:45:02][D][elero:451]: rcv'd: len=29, cnt=119, typ=0xca, typ2=0x10, hop=00, syst=01, chl=153, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[21:45:03][D][elero:451]: rcv'd: len=29, cnt=54, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=146, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:46:20][D][elero:451]: rcv'd: len=29, cnt=58, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=09, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-88.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:46:20][D][elero:451]: rcv'd: len=29, cnt=59, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=09, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-88.0, lqi=51, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:50:02][D][elero:451]: rcv'd: len=29, cnt=120, typ=0xca, typ2=0x10, hop=00, syst=01, chl=154, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[21:50:03][D][elero:451]: rcv'd: len=29, cnt=56, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=147, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:50:03][D][elero:451]: rcv'd: len=29, cnt=57, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=147, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[21:51:20][D][elero:451]: rcv'd: len=29, cnt=61, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=10, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[21:55:02][D][elero:451]: rcv'd: len=29, cnt=123, typ=0xca, typ2=0x10, hop=00, syst=01, chl=155, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:55:03][D][elero:451]: rcv'd: len=29, cnt=59, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=148, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[21:56:20][D][elero:451]: rcv'd: len=29, cnt=62, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=11, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-91.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[21:56:20][D][elero:451]: rcv'd: len=29, cnt=63, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=11, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[22:00:03][D][elero:451]: rcv'd: len=29, cnt=61, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=149, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[22:01:20][D][elero:451]: rcv'd: len=29, cnt=64, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=12, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-89.0, lqi=44, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[22:05:02][D][elero:451]: rcv'd: len=29, cnt=126, typ=0xca, typ2=0x10, hop=00, syst=01, chl=157, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[22:10:02][D][elero:451]: rcv'd: len=29, cnt=127, typ=0xca, typ2=0x10, hop=00, syst=01, chl=158, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[22:10:02][D][elero:451]: rcv'd: len=29, cnt=128, typ=0xca, typ2=0x10, hop=00, syst=01, chl=158, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[22:11:20][D][elero:451]: rcv'd: len=29, cnt=68, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=14, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=44, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[22:15:02][D][elero:451]: rcv'd: len=29, cnt=129, typ=0xca, typ2=0x10, hop=00, syst=01, chl=159, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x10]
[22:16:20][D][elero:451]: rcv'd: len=29, cnt=71, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=15, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x01 0x90]
[22:18:03][D][cover:076]: 'Living room window' - Setting
[22:18:03][D][cover:084]:   Position: 15%
[22:18:03][D][cover:170]: 'Living room window' - Publishing:
[22:18:03][D][cover:173]:   Position: 100%
[22:18:03][D][cover:186]:   Current Operation: CLOSING
[22:18:03][D][cover:170]: 'Living room window' - Publishing:
[22:18:03][D][cover:173]:   Position: 100%
[22:18:03][D][cover:186]:   Current Operation: CLOSING
[22:18:03][D][cover:076]: 'Living room balcony door' - Setting
[22:18:03][D][cover:084]:   Position: 15%
[22:18:03][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:03][D][cover:173]:   Position: 100%
[22:18:04][D][cover:186]:   Current Operation: CLOSING
[22:18:04][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:04][D][cover:173]:   Position: 100%
[22:18:04][D][cover:186]:   Current Operation: CLOSING
[22:18:04][D][cover:076]: 'Dining room' - Setting
[22:18:04][D][cover:084]:   Position: 15%
[22:18:04][D][cover:170]: 'Dining room' - Publishing:
[22:18:04][D][cover:173]:   Position: 100%
[22:18:04][D][cover:186]:   Current Operation: CLOSING
[22:18:04][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:04][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:04][D][cover:170]: 'Dining room' - Publishing:
[22:18:04][D][cover:173]:   Position: 100%
[22:18:04][D][cover:186]:   Current Operation: CLOSING
[22:18:04][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:04][D][elero.cover:086]: Retry #1 for blind 0xa8ab21
[22:18:04][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:04][D][elero.cover:086]: Retry #2 for blind 0xa8ab21
[22:18:04][E][elero:186]: Timed out waiting for TX: 0x0d
[22:18:04][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:04][W][component:237]: Component elero.cover took a long time for an operation (60 ms).
[22:18:04][W][component:238]: Components should block for at most 30 ms.
[22:18:04][D][elero:451]: rcv'd: len=29, cnt=72, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=16, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-86.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:04][D][elero:451]: rcv'd: len=29, cnt=68, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=153, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:04][D][cover:170]: 'Living room window' - Publishing:
[22:18:04][D][cover:173]:   Position: 97%
[22:18:04][D][cover:186]:   Current Operation: CLOSING
[22:18:04][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:05][D][cover:173]:   Position: 97%
[22:18:05][D][cover:186]:   Current Operation: CLOSING
[22:18:05][D][cover:170]: 'Dining room' - Publishing:
[22:18:05][D][cover:173]:   Position: 96%
[22:18:05][D][cover:186]:   Current Operation: CLOSING
[22:18:06][D][cover:170]: 'Living room window' - Publishing:
[22:18:06][D][cover:173]:   Position: 93%
[22:18:06][D][cover:186]:   Current Operation: CLOSING
[22:18:06][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:06][D][cover:173]:   Position: 93%
[22:18:06][D][cover:186]:   Current Operation: CLOSING
[22:18:06][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:06][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:06][D][cover:170]: 'Dining room' - Publishing:
[22:18:06][D][cover:173]:   Position: 91%
[22:18:06][D][cover:186]:   Current Operation: CLOSING
[22:18:06][D][elero:451]: rcv'd: len=29, cnt=70, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=155, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-83.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:06][D][cover:170]: 'Living room window' - Publishing:
[22:18:06][D][cover:173]:   Position: 90%
[22:18:06][D][cover:186]:   Current Operation: CLOSING
[22:18:07][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:07][D][cover:173]:   Position: 90%
[22:18:07][D][cover:186]:   Current Operation: CLOSING
[22:18:07][D][cover:170]: 'Dining room' - Publishing:
[22:18:07][D][cover:173]:   Position: 87%
[22:18:07][D][cover:186]:   Current Operation: CLOSING
[22:18:07][D][cover:170]: 'Living room window' - Publishing:
[22:18:07][D][cover:173]:   Position: 86%
[22:18:07][D][cover:186]:   Current Operation: CLOSING
[22:18:08][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:08][D][cover:173]:   Position: 86%
[22:18:08][D][cover:186]:   Current Operation: CLOSING
[22:18:08][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:08][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:08][D][cover:170]: 'Dining room' - Publishing:
[22:18:08][D][cover:173]:   Position: 83%
[22:18:08][D][cover:186]:   Current Operation: CLOSING
[22:18:08][D][elero:451]: rcv'd: len=29, cnt=71, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=156, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:08][D][elero:451]: rcv'd: len=29, cnt=134, typ=0xca, typ2=0x10, hop=00, syst=01, chl=163, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-81.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:08][D][elero:451]: rcv'd: len=29, cnt=76, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=19, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-91.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:08][D][elero:451]: rcv'd: len=29, cnt=135, typ=0xca, typ2=0x10, hop=00, syst=01, chl=163, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:08][D][cover:170]: 'Living room window' - Publishing:
[22:18:08][D][cover:173]:   Position: 83%
[22:18:08][D][cover:186]:   Current Operation: CLOSING
[22:18:09][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:09][D][cover:173]:   Position: 83%
[22:18:09][D][cover:186]:   Current Operation: CLOSING
[22:18:09][D][cover:170]: 'Dining room' - Publishing:
[22:18:09][D][cover:173]:   Position: 79%
[22:18:09][D][cover:186]:   Current Operation: CLOSING
[22:18:09][D][cover:170]: 'Living room window' - Publishing:
[22:18:09][D][cover:173]:   Position: 79%
[22:18:09][D][cover:186]:   Current Operation: CLOSING
[22:18:10][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:10][D][cover:173]:   Position: 80%
[22:18:10][D][cover:186]:   Current Operation: CLOSING
[22:18:10][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:10][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:10][D][cover:170]: 'Dining room' - Publishing:
[22:18:10][D][cover:173]:   Position: 75%
[22:18:10][D][cover:186]:   Current Operation: CLOSING
[22:18:10][D][elero:451]: rcv'd: len=29, cnt=136, typ=0xca, typ2=0x10, hop=00, syst=01, chl=164, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-80.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:10][D][elero:451]: rcv'd: len=29, cnt=78, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=20, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-90.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:10][D][cover:170]: 'Living room window' - Publishing:
[22:18:10][D][cover:173]:   Position: 76%
[22:18:11][D][cover:186]:   Current Operation: CLOSING
[22:18:11][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:11][D][cover:173]:   Position: 76%
[22:18:11][D][cover:186]:   Current Operation: CLOSING
[22:18:11][D][cover:170]: 'Dining room' - Publishing:
[22:18:11][D][cover:173]:   Position: 71%
[22:18:11][D][cover:186]:   Current Operation: CLOSING
[22:18:11][D][cover:170]: 'Living room window' - Publishing:
[22:18:11][D][cover:173]:   Position: 72%
[22:18:11][D][cover:186]:   Current Operation: CLOSING
[22:18:12][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:12][D][cover:173]:   Position: 73%
[22:18:12][D][cover:186]:   Current Operation: CLOSING
[22:18:12][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:12][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:12][D][cover:170]: 'Dining room' - Publishing:
[22:18:12][D][cover:173]:   Position: 66%
[22:18:12][D][cover:186]:   Current Operation: CLOSING
[22:18:12][D][elero:451]: rcv'd: len=29, cnt=79, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=21, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-91.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:12][D][elero:451]: rcv'd: len=29, cnt=138, typ=0xca, typ2=0x10, hop=00, syst=01, chl=165, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:12][D][elero:451]: rcv'd: len=29, cnt=74, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=158, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-86.0, lqi=49, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:12][D][elero:451]: rcv'd: len=29, cnt=75, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=158, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-87.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:13][D][cover:170]: 'Living room window' - Publishing:
[22:18:13][D][cover:173]:   Position: 69%
[22:18:13][D][cover:186]:   Current Operation: CLOSING
[22:18:13][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:13][D][cover:173]:   Position: 70%
[22:18:13][D][cover:186]:   Current Operation: CLOSING
[22:18:13][D][cover:170]: 'Dining room' - Publishing:
[22:18:13][D][cover:173]:   Position: 62%
[22:18:13][D][cover:186]:   Current Operation: CLOSING
[22:18:14][D][cover:170]: 'Living room window' - Publishing:
[22:18:14][D][cover:173]:   Position: 65%
[22:18:15][D][cover:186]:   Current Operation: CLOSING
[22:18:15][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:15][D][elero.cover:086]: Retry #0 for blind 0x99601d
[22:18:15][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:15][D][cover:173]:   Position: 66%
[22:18:15][D][cover:186]:   Current Operation: CLOSING
[22:18:15][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:15][D][elero.cover:086]: Retry #1 for blind 0x99601d
[22:18:15][D][cover:170]: 'Dining room' - Publishing:
[22:18:15][D][cover:173]:   Position: 58%
[22:18:15][D][cover:186]:   Current Operation: CLOSING
[22:18:15][D][elero:451]: rcv'd: len=29, cnt=81, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=22, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-88.0, lqi=44, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:15][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:15][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:15][D][elero:451]: rcv'd: len=29, cnt=76, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=159, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-89.0, lqi=43, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:16][D][cover:170]: 'Living room window' - Publishing:
[22:18:16][D][cover:173]:   Position: 58%
[22:18:16][D][cover:186]:   Current Operation: CLOSING
[22:18:16][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:16][D][cover:173]:   Position: 60%
[22:18:16][D][cover:186]:   Current Operation: CLOSING
[22:18:16][D][cover:170]: 'Dining room' - Publishing:
[22:18:16][D][cover:173]:   Position: 50%
[22:18:16][D][cover:186]:   Current Operation: CLOSING
[22:18:16][D][elero:451]: rcv'd: len=29, cnt=83, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=23, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-89.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:16][D][elero:451]: rcv'd: len=29, cnt=142, typ=0xca, typ2=0x10, hop=00, syst=01, chl=167, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-79.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:16][D][elero:451]: rcv'd: len=29, cnt=84, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=23, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-92.0, lqi=43, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:16][D][elero:451]: rcv'd: len=29, cnt=79, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=160, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-85.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:17][D][cover:170]: 'Living room window' - Publishing:
[22:18:17][D][cover:173]:   Position: 55%
[22:18:17][D][cover:186]:   Current Operation: CLOSING
[22:18:17][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:17][D][cover:173]:   Position: 56%
[22:18:17][D][cover:186]:   Current Operation: CLOSING
[22:18:17][D][cover:170]: 'Dining room' - Publishing:
[22:18:17][D][cover:173]:   Position: 45%
[22:18:17][D][cover:186]:   Current Operation: CLOSING
[22:18:18][D][cover:170]: 'Living room window' - Publishing:
[22:18:18][D][cover:173]:   Position: 51%
[22:18:18][D][cover:186]:   Current Operation: CLOSING
[22:18:18][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:18][D][cover:173]:   Position: 53%
[22:18:18][D][cover:186]:   Current Operation: CLOSING
[22:18:18][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:18][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:18][D][cover:170]: 'Dining room' - Publishing:
[22:18:18][D][cover:173]:   Position: 41%
[22:18:18][D][cover:186]:   Current Operation: CLOSING
[22:18:18][D][elero:451]: rcv'd: len=29, cnt=85, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=24, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-91.0, lqi=44, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:18][D][elero:451]: rcv'd: len=29, cnt=144, typ=0xca, typ2=0x10, hop=00, syst=01, chl=168, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-79.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:18][D][elero:451]: rcv'd: len=29, cnt=80, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=161, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-92.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:18][D][elero:451]: rcv'd: len=29, cnt=145, typ=0xca, typ2=0x10, hop=00, syst=01, chl=168, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-80.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:19][D][cover:170]: 'Living room window' - Publishing:
[22:18:19][D][cover:173]:   Position: 48%
[22:18:19][D][cover:186]:   Current Operation: CLOSING
[22:18:19][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:19][D][cover:173]:   Position: 50%
[22:18:19][D][cover:186]:   Current Operation: CLOSING
[22:18:19][D][cover:170]: 'Dining room' - Publishing:
[22:18:19][D][cover:173]:   Position: 37%
[22:18:19][D][cover:186]:   Current Operation: CLOSING
[22:18:20][D][cover:170]: 'Living room window' - Publishing:
[22:18:20][D][cover:173]:   Position: 44%
[22:18:20][D][cover:186]:   Current Operation: CLOSING
[22:18:20][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:20][D][cover:173]:   Position: 46%
[22:18:20][D][cover:186]:   Current Operation: CLOSING
[22:18:20][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:20][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:20][D][cover:170]: 'Dining room' - Publishing:
[22:18:20][D][cover:173]:   Position: 33%
[22:18:20][D][cover:186]:   Current Operation: CLOSING
[22:18:21][D][cover:170]: 'Living room window' - Publishing:
[22:18:21][D][cover:173]:   Position: 41%
[22:18:21][D][cover:186]:   Current Operation: CLOSING
[22:18:21][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:21][D][cover:173]:   Position: 43%
[22:18:21][D][cover:186]:   Current Operation: CLOSING
[22:18:21][D][cover:170]: 'Dining room' - Publishing:
[22:18:21][D][cover:173]:   Position: 29%
[22:18:21][D][cover:186]:   Current Operation: CLOSING
[22:18:22][D][cover:170]: 'Living room window' - Publishing:
[22:18:22][D][cover:173]:   Position: 38%
[22:18:22][D][cover:186]:   Current Operation: CLOSING
[22:18:22][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:22][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:22][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:22][D][cover:173]:   Position: 40%
[22:18:22][D][cover:186]:   Current Operation: CLOSING
[22:18:22][D][cover:170]: 'Dining room' - Publishing:
[22:18:22][D][cover:173]:   Position: 24%
[22:18:22][D][cover:186]:   Current Operation: CLOSING
[22:18:23][D][cover:170]: 'Living room window' - Publishing:
[22:18:23][D][cover:173]:   Position: 34%
[22:18:23][D][cover:186]:   Current Operation: CLOSING
[22:18:23][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:23][D][cover:173]:   Position: 36%
[22:18:23][D][cover:186]:   Current Operation: CLOSING
[22:18:23][D][cover:170]: 'Dining room' - Publishing:
[22:18:23][D][cover:173]:   Position: 20%
[22:18:23][D][cover:186]:   Current Operation: CLOSING
[22:18:24][D][cover:170]: 'Living room window' - Publishing:
[22:18:24][D][cover:173]:   Position: 31%
[22:18:24][D][cover:186]:   Current Operation: CLOSING
[22:18:24][E][elero:227]: Error transferring, 28 bytes left in buffer
[22:18:24][D][elero.cover:086]: Retry #0 for blind 0xa8ab21
[22:18:24][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:24][D][cover:173]:   Position: 33%
[22:18:24][D][cover:186]:   Current Operation: CLOSING
[22:18:24][D][cover:170]: 'Dining room' - Publishing:
[22:18:24][D][cover:173]:   Position: 16%
[22:18:24][D][cover:186]:   Current Operation: CLOSING
[22:18:24][D][elero:451]: rcv'd: len=29, cnt=90, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=27, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-92.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:24][D][elero:451]: rcv'd: len=29, cnt=91, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=27, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-98.0, lqi=49, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:24][D][elero:451]: rcv'd: len=29, cnt=81, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=164, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-84.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:24][D][cover:170]: 'Dining room' - Publishing:
[22:18:24][D][cover:173]:   Position: 15%
[22:18:24][D][cover:186]:   Current Operation: CLOSING
[22:18:24][D][elero:451]: rcv'd: len=29, cnt=82, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=166, src=0xa8ab21, bwd=0xa8ab21, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-84.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0d 0x10]
[22:18:25][D][elero:451]: rcv'd: len=29, cnt=150, typ=0xca, typ2=0x10, hop=00, syst=01, chl=171, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-80.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:25][D][cover:170]: 'Living room window' - Publishing:
[22:18:25][D][cover:173]:   Position: 27%
[22:18:25][D][cover:186]:   Current Operation: CLOSING
[22:18:25][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:25][D][cover:173]:   Position: 29%
[22:18:25][D][cover:186]:   Current Operation: CLOSING
[22:18:26][D][cover:170]: 'Living room window' - Publishing:
[22:18:26][D][cover:173]:   Position: 24%
[22:18:26][D][cover:186]:   Current Operation: CLOSING
[22:18:26][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:26][D][cover:173]:   Position: 26%
[22:18:26][D][cover:186]:   Current Operation: CLOSING
[22:18:26][D][elero:451]: rcv'd: len=29, cnt=151, typ=0xca, typ2=0x10, hop=00, syst=01, chl=172, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-81.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:26][D][elero:451]: rcv'd: len=29, cnt=93, typ=0xca, typ2=0x10, hop=0a, syst=01, chl=28, src=0x99601d, bwd=0x99601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-94.0, lqi=45, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:26][D][elero:451]: rcv'd: len=29, cnt=152, typ=0xca, typ2=0x10, hop=00, syst=01, chl=172, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-81.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x10]
[22:18:27][D][cover:170]: 'Living room window' - Publishing:
[22:18:27][D][cover:173]:   Position: 20%
[22:18:27][D][cover:186]:   Current Operation: CLOSING
[22:18:27][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:27][D][cover:173]:   Position: 23%
[22:18:27][D][cover:186]:   Current Operation: CLOSING
[22:18:28][D][cover:170]: 'Living room window' - Publishing:
[22:18:28][D][cover:173]:   Position: 17%
[22:18:28][D][cover:186]:   Current Operation: CLOSING
[22:18:28][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:28][D][cover:173]:   Position: 19%
[22:18:28][D][cover:186]:   Current Operation: CLOSING
[22:18:28][D][elero:451]: rcv'd: len=29, cnt=154, typ=0xca, typ2=0x10, hop=00, syst=01, chl=173, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-81.0, lqi=48, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:29][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:29][D][cover:173]:   Position: 16%
[22:18:29][D][cover:186]:   Current Operation: CLOSING
[22:18:29][D][elero:451]: rcv'd: len=29, cnt=155, typ=0xca, typ2=0x10, hop=00, syst=01, chl=174, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=46, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0b 0x90]
[22:18:29][D][cover:170]: 'Living room balcony door' - Publishing:
[22:18:29][D][cover:173]:   Position: 15%
[22:18:29][D][cover:186]:   Current Operation: CLOSING
[22:18:29][D][elero:451]: rcv'd: len=29, cnt=156, typ=0xca, typ2=0x10, hop=00, syst=01, chl=174, src=0xac601d, bwd=0xac601d, fwd=0x4daf0c, #dst=01, dst=4daf0c, rssi=-82.0, lqi=47, crc= 1, payload=[0x01 0x60 0x00 0x00 0x00 0x00 0x00 0x00 0x0d 0x90]
[22:18:58][D][esp32.preferences:114]: Saving 3 preferences to flash...
[22:18:58][D][esp32.preferences:143]: Saving 3 preferences to flash: 1 cached, 2 written, 0 failed
andyboeh commented 2 months ago

I've never experienced those "Error transferring, 28 bytes left in buffer" message before. I can try to increase the timeout a bit, I'm not sure how long it really takes to transfer everything.

What I have seen is the "Timed out waiting for TX" message, although I'm not sure for which reason.

I'll have a look, but it's going to take a couple of days.

peterjuras commented 2 months ago

Thanks, a lot for looking into it! It works correctly most of the times, therefore it might also be unrelated to this issue.

peterjuras commented 1 month ago

Hi @andyboeh ,

I tried to play around with the number of timeouts, packages to send & retries but sadly it still appears to error out (Error transferring, 28 bytes left in buffer) sometimes and some commands are either not sent or make it to the blinds. Do you have any additional hints on what could be wrong?

andyboeh commented 1 month ago

atm, every request is sent twice within a very short time span. Have you tried setting ELERO_SEND_PACKETS to 1 or have you tried increasing the delay between the packets, i.e. ELERO_DELAY_SEND_PACKETS? And I can see a design problem with how commands are handled: There is currently one loop per cover that is responsible for sending commands and keeping track of the timeouts. When you have several blinds, it might happen that commands from different blinds are sent very, very frequently.

I think that there needs to be one loop responsible for sending commands for the whole component, not per cover. And then it would be necessary to block polling covers when a cover is moving so that we don't block the radio.

peterjuras commented 4 weeks ago

Hi @andyboeh , yes I tried playing around with ELERO_SEND_PACKETS & ELERO_DELAY_SEND_PACKETS - both decreasing and increasing the values (independently). It did not really improve the situation, but sometimes made it worse.