search

john30 / ebusd

daemon for communication with eBUS heating systems
GNU General Public License v3.0
594 stars 136 forks source link

Unable to find any device on the bus when signal is acquired #1164

Closed aspyk closed 10 months ago

aspyk commented 10 months ago

Description

I run for the first time the docker version of ebusd on a rapsberry pi with a v5 adapter. The adapter is connected to the heater directly. Everything seems to run fine except it looks like no other device is found on the ebus. Here is the output:

docker run --rm -it --device=/dev/ttyAMA0 --name=ebusd john30/ebusd /bin/bash
root@969d3973bfbe:/# ebusd --scanconfig -d ens:/dev/ttyAMA0 --latency=50
2024-01-22 08:13:41.988 [bus notice] device status: transport opened
2024-01-22 08:13:41.989 [main notice] ebusd 23.3.23.3 started with broadcast scan on device: /dev/ttyAMA0, serial high speed, enhanced
2024-01-22 08:13:43.331 [bus notice] bus started with own address 31/36
2024-01-22 08:13:43.331 [bus notice] signal acquired
2024-01-22 08:13:43.331 [bus notice] device status: reset, supports info
2024-01-22 08:13:43.367 [bus notice] device status: extra info: firmware 1.1[3501].1[3501], jumpers 0x03
2024-01-22 08:13:53.333 [main notice] starting initial broadcast scan
2024-01-22 08:13:53.426 [update notice] sent write broadcast queryexistence QQ=31: 
2024-01-22 08:15:03.428 [main notice] scan completed 1 time(s), check again
2024-01-22 08:15:53.708 [main notice] update check: OK, device firmware 1[4106] available
2024-01-22 08:16:13.709 [main notice] scan completed 2 time(s), check again
2024-01-22 08:17:23.711 [main notice] scan completed 3 time(s), check again
2024-01-22 08:18:33.714 [main notice] scan completed 4 time(s), check again
2024-01-22 08:19:43.716 [main notice] scan completed 5 time(s), check again
2024-01-22 08:20:53.719 [main notice] scan completed 6 time(s), end

And here the output of ebusctl info in another terminal connected to the container:

docker exec -it ebusd /bin/bash
root@969d3973bfbe:/# ebusctl info
version: ebusd 23.3.23.3
update check: OK, device firmware 1[4106] available
device: /dev/ttyAMA0, serial high speed, enhanced, firmware 1.1[3501].1[3501]
signal: acquired
symbol rate: 23
max symbol rate: 30
min arbitration micros: 22
max arbitration micros: 22
min symbol latency: 4
max symbol latency: 4
scan: finished
reconnects: 0
masters: 1
messages: 11
conditional: 0
poll: 0
update: 4
address 31: master #8, ebusd
address 36: slave #8, ebusd

Do you know what's going wrong ? Thank you !

Actual behavior

The scan seems to be ok but no other device is found on the bus (except itself) in ebusctl info.

Expected behavior

another address should be detected in the output of ebusctl info.

ebusd version

current source from git

ebusd arguments

--scanconfig -d ens:/dev/ttyAMA0 --latency=50

Operating system

Debian 11 (Bullseye) / Ubuntu 20-21 / Raspbian 11 / Raspberry Pi OS 11 (including lite)

CPU architecture

arm64

Dockerized

same as ebusd version

Hardware interface

Adapter Shield v5 via Raspberry GPIO

Related integration

No response

Logs

output with loglevel=debug

2024-01-22 13:40:37.649 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:40:37.649 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.652 [network debug] SSL BIO state 2 res -1
2024-01-22 13:40:37.736 [network debug] SSL BIO state 3 res 1
2024-01-22 13:40:37.736 [network debug] SSL BIO state 4 res 3
2024-01-22 13:40:37.736 [network debug] SSL BIO state 6 res 0
2024-01-22 13:40:37.736 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.756 [network debug] SSL BIO state 5 res -1
2024-01-22 13:40:37.757 [network debug] SSL BIO state 5 res 1
2024-01-22 13:40:37.757 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.777 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.797 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.818 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.818 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.838 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.859 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.879 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.899 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.901 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.902 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.908 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.911 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.911 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.911 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.913 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.913 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:40:37.913 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:37.913 [main info] configPath URL is valid
2024-01-22 13:40:37.914 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:40:37.917 [bus notice] device status: transport opened
2024-01-22 13:40:37.917 [main info] registering data handlers
2024-01-22 13:40:37.917 [main info] registered data handlers
2024-01-22 13:40:37.917 [main notice] ebusd 23.3.23.3 started with broadcast scan on device: /dev/ttyAMA0, serial high speed, enhanced
2024-01-22 13:40:37.917 [main info] loading configuration files from https://cfg.ebusd.eu/
2024-01-22 13:40:37.917 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:40:37.917 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.919 [network debug] SSL BIO state 2 res -1
2024-01-22 13:40:37.932 [network debug] SSL BIO state 3 res 1
2024-01-22 13:40:37.932 [network debug] SSL BIO state 4 res 4
2024-01-22 13:40:37.932 [network debug] SSL BIO state 6 res 0
2024-01-22 13:40:37.933 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.953 [network debug] SSL BIO state 5 res -1
2024-01-22 13:40:37.953 [network debug] SSL BIO state 5 res 1
2024-01-22 13:40:37.953 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.973 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:37.974 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:37.994 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.014 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.034 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.036 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.037 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.044 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.047 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.047 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.048 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.049 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.049 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:40:38.049 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.091 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.091 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.092 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.092 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.113 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.113 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.114 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.114 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.236 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:40:38.238 [main info] reading templates /
2024-01-22 13:40:38.239 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:40:38.239 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.240 [network debug] SSL BIO state 2 res -1
2024-01-22 13:40:38.257 [network debug] SSL BIO state 3 res 1
2024-01-22 13:40:38.257 [network debug] SSL BIO state 4 res 4
2024-01-22 13:40:38.257 [network debug] SSL BIO state 6 res 0
2024-01-22 13:40:38.257 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.278 [network debug] SSL BIO state 5 res -1
2024-01-22 13:40:38.278 [network debug] SSL BIO state 5 res 1
2024-01-22 13:40:38.278 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.298 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.299 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.319 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.339 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.359 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.380 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.381 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.382 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.386 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.389 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.390 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.390 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.391 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.391 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:40:38.391 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.452 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.452 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.453 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.453 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.474 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.474 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.475 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.475 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.516 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:40:38.520 [main info] read templates in /
2024-01-22 13:40:38.520 [main info] reading file broadcast.csv
2024-01-22 13:40:38.521 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:40:38.521 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.525 [network debug] SSL BIO state 2 res -1
2024-01-22 13:40:38.537 [network debug] SSL BIO state 3 res 1
2024-01-22 13:40:38.537 [network debug] SSL BIO state 4 res 4
2024-01-22 13:40:38.538 [network debug] SSL BIO state 6 res 0
2024-01-22 13:40:38.538 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.558 [network debug] SSL BIO state 5 res -1
2024-01-22 13:40:38.558 [network debug] SSL BIO state 5 res 1
2024-01-22 13:40:38.558 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.579 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.579 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.599 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.619 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.640 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.642 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.642 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.663 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.667 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.670 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.670 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.670 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.671 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.672 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:40:38.672 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.713 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.713 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.714 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.714 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.734 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.734 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.735 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.735 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.756 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:40:38.760 [main info] successfully read file broadcast.csv
2024-01-22 13:40:38.760 [main info] reading file memory.csv
2024-01-22 13:40:38.760 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:40:38.761 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.762 [network debug] SSL BIO state 2 res -1
2024-01-22 13:40:38.771 [network debug] SSL BIO state 3 res 1
2024-01-22 13:40:38.771 [network debug] SSL BIO state 4 res 4
2024-01-22 13:40:38.773 [network debug] SSL BIO state 6 res 0
2024-01-22 13:40:38.773 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.793 [network debug] SSL BIO state 5 res -1
2024-01-22 13:40:38.793 [network debug] SSL BIO state 5 res 1
2024-01-22 13:40:38.793 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.813 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.834 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.854 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.854 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.875 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.895 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:40:38.915 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.918 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.918 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.923 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.926 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.926 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.926 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.927 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.927 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:40:38.927 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:38.988 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.988 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.989 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:38.989 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:39.009 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:39.010 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:39.010 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:40:39.011 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:40:39.032 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:40:39.034 [main info] successfully read file memory.csv
2024-01-22 13:40:39.035 [main info] read config files, got 11 messages
2024-01-22 13:40:39.036 [bus notice] bus started with own address 31/36
2024-01-22 13:40:39.036 [bus notice] signal acquired
2024-01-22 13:40:39.036 [bus notice] device status: reset, supports info
2024-01-22 13:40:39.087 [bus notice] device status: extra info: firmware 1.1[3501].1[3501], jumpers 0x03
2024-01-22 13:40:49.038 [main debug] performing regular tasks
2024-01-22 13:40:49.038 [main notice] starting initial broadcast scan
2024-01-22 13:40:49.038 [bus info] send message: 31fe07fe00
2024-01-22 13:40:49.044 [bus debug] start request 31
2024-01-22 13:40:49.044 [bus debug] arbitration start with 31
2024-01-22 13:40:49.091 [bus debug] arbitration won
2024-01-22 13:40:49.091 [bus debug] arbitration delay 22 micros
2024-01-22 13:40:49.091 [bus info] arbitration delay 22 - 22 micros
2024-01-22 13:40:49.092 [bus debug] switching from ready to send command
2024-01-22 13:40:49.097 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.097 [bus info] send/receive symbol latency 4 - 4 ms
2024-01-22 13:40:49.103 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.107 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.112 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.112 [bus debug] switching from send command to send command CRC
2024-01-22 13:40:49.117 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.117 [update info] sent BC cmd: 31fe07fe00
2024-01-22 13:40:49.117 [update notice] sent write broadcast queryexistence QQ=31: 
2024-01-22 13:40:49.118 [bus debug] notify request: done
2024-01-22 13:40:49.118 [bus debug] read res: 
2024-01-22 13:40:49.118 [bus debug] switching from send command CRC to send SYN
2024-01-22 13:40:49.123 [bus debug] send/receive symbol latency 4 ms
2024-01-22 13:40:49.123 [bus debug] switching from send SYN to ready
2024-01-22 13:40:59.118 [main debug] performing regular tasks
2024-01-22 13:41:09.119 [main debug] performing regular tasks
2024-01-22 13:41:19.119 [main debug] performing regular tasks
2024-01-22 13:41:29.120 [main debug] performing regular tasks
2024-01-22 13:41:39.120 [main debug] performing regular tasks
2024-01-22 13:41:49.121 [main debug] performing regular tasks
2024-01-22 13:41:59.121 [main debug] performing regular tasks
2024-01-22 13:41:59.121 [main notice] scan completed 1 time(s), check again
2024-01-22 13:42:09.122 [main debug] performing regular tasks
2024-01-22 13:42:19.122 [main debug] performing regular tasks
2024-01-22 13:42:29.122 [main debug] performing regular tasks
2024-01-22 13:42:39.123 [main debug] performing regular tasks
2024-01-22 13:42:49.123 [main debug] performing regular tasks
2024-01-22 13:42:49.124 [network debug] SSL state 0=before SSL initialization: type 0x10=start, val 1=?
2024-01-22 13:42:49.124 [network debug] SSL state 0=before SSL initialization: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.126 [network debug] SSL BIO state 2 res -1
2024-01-22 13:42:49.144 [network debug] SSL BIO state 3 res 1
2024-01-22 13:42:49.145 [network debug] SSL BIO state 4 res 7
2024-01-22 13:42:49.145 [network debug] SSL BIO state 6 res 0
2024-01-22 13:42:49.145 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.166 [network debug] SSL BIO state 5 res -1
2024-01-22 13:42:49.166 [network debug] SSL BIO state 5 res 1
2024-01-22 13:42:49.166 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.186 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.207 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.207 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.228 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.248 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.268 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.288 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1002=exit,connect, val -1=?
2024-01-22 13:42:49.309 [network debug] SSL state 12=SSLv3/TLS write client hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.311 [network debug] SSL state 3=SSLv3/TLS read server hello: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.311 [network debug] SSL state 38=TLSv1.3 read encrypted extensions: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.316 [network debug] SSL state 4=SSLv3/TLS read server certificate: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.320 [network debug] SSL state 39=TLSv1.3 read server certificate verify: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.320 [network debug] SSL state 11=SSLv3/TLS read finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.320 [network debug] SSL state 16=SSLv3/TLS write change cipher spec: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.323 [network debug] SSL state 18=SSLv3/TLS write finished: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.323 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x20=done, val 1=?
2024-01-22 13:42:49.323 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:42:49.366 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.366 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.366 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.366 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:42:49.387 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.387 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.387 [network debug] SSL state 9=SSLv3/TLS read server session ticket: type 0x1001=loop,connect, val 1=?
2024-01-22 13:42:49.388 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x1002=exit,connect, val 1=?
2024-01-22 13:42:49.408 [network debug] SSL state 1=SSL negotiation finished successfully: type 0x4008=write,alert, val 256=close notify
2024-01-22 13:42:49.410 [main notice] update check: OK, device firmware 1[4106] available
2024-01-22 13:42:59.411 [main debug] performing regular tasks
2024-01-22 13:43:09.411 [main debug] performing regular tasks
2024-01-22 13:43:09.411 [main notice] scan completed 2 time(s), check again
2024-01-22 13:43:19.412 [main debug] performing regular tasks
2024-01-22 13:43:29.412 [main debug] performing regular tasks
2024-01-22 13:43:39.413 [main debug] performing regular tasks
2024-01-22 13:43:49.413 [main debug] performing regular tasks
2024-01-22 13:43:59.414 [main debug] performing regular tasks
2024-01-22 13:44:09.414 [main debug] performing regular tasks
2024-01-22 13:44:19.415 [main debug] performing regular tasks
2024-01-22 13:44:19.415 [main notice] scan completed 3 time(s), check again
2024-01-22 13:44:29.416 [main debug] performing regular tasks
2024-01-22 13:44:39.416 [main debug] performing regular tasks
2024-01-22 13:44:49.417 [main debug] performing regular tasks
2024-01-22 13:44:59.417 [main debug] performing regular tasks
2024-01-22 13:45:09.418 [main debug] performing regular tasks
2024-01-22 13:45:19.418 [main debug] performing regular tasks
2024-01-22 13:45:29.419 [main debug] performing regular tasks
2024-01-22 13:45:29.419 [main notice] scan completed 4 time(s), check again
2024-01-22 13:45:39.419 [main debug] performing regular tasks
2024-01-22 13:45:49.419 [main debug] performing regular tasks
2024-01-22 13:45:59.420 [main debug] performing regular tasks
2024-01-22 13:46:09.420 [main debug] performing regular tasks
2024-01-22 13:46:19.421 [main debug] performing regular tasks
john30 commented 10 months ago

well if no unit is willing to respond to the broadcast scan, then just try with a full scan instead. anyway, if there is no traffic at all on the bus, it'll probably be hard to find useful data. either way, this does not look like an ebusd issue in the first place, so closing

aspyk commented 10 months ago

Ok indeed the full scan make the device pop... I'm going to start the exploration now, thank you very much!