search

john30 / ebusd

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

"device invalid" after upgrade to v3.2 #218

Closed stefaanv closed 5 years ago

stefaanv commented 5 years ago

Hi, I had a rather successfull deployment of ebusd but decided to upgrade from 3.0 to 3.2 because I had a problem writing the Hc1OPMode parameter. I installed the ebusd-3.2_amd64-stretch_mqtt1.deb package which I downloaded from the [ebusd.eu] website. However, after the upgrade ebusd is unable to communicate with my esera eBUS Koppler Ethernet while nothing changed at the koppler side. The error message is listed below. ebusd is running on an AMD64 computer (an intel NUC) with Ubuntu 16.04 Xenial operating system. Pinging the esera device from the computer works fine. Running the same command with sudo has the same effect.

Any ideas on this ?

$ebusd --scanconfig -f -d 192.168.0.36:5000
2018-11-03 19:43:37.972 [main notice] ebusd 3.2.v3.2 started with auto scan
2018-11-03 19:43:38.221 [bus error] unable to open 192.168.0.36:5000: ERR: generic I/O error
2018-11-03 19:43:38.222 [bus notice] bus started with own address 31/36
2018-11-03 19:43:38.222 [bus notice] device invalid
2018-11-03 19:43:43.223 [bus error] unable to open 192.168.0.36:5000: ERR: generic I/O error
2018-11-03 19:43:43.223 [bus notice] device invalid

$ ping 192.168.0.36
PING 192.168.0.36 (192.168.0.36) 56(84) bytes of data.
64 bytes from 192.168.0.36: icmp_seq=1 ttl=128 time=0.223 ms
64 bytes from 192.168.0.36: icmp_seq=2 ttl=128 time=0.449 ms
stefaanv commented 5 years ago

I tried to downgrade back to V3.0, but this isn't working

$ sudo apt install ./ebusd-3.0_amd64_mqtt1.deb
Reading package lists... Done
Building dependency tree
Reading state information... Done
Note, selecting 'ebusd' instead of './ebusd-3.0_amd64_mqtt1.deb'
The following packages will be DOWNGRADED:
  ebusd
0 upgraded, 0 newly installed, 1 downgraded, 0 to remove and 0 not upgraded.
Need to get 0 B/211 kB of archives.
After this operation, 0 B of additional disk space will be used.
Do you want to continue? [Y/n] y
Get:1 /home/stefaan/ebusd-3.0_amd64_mqtt1.deb ebusd amd64 3.0 [211 kB]
dpkg: warning: downgrading ebusd from 3.2 to 3.0
(Reading database ... 148641 files and directories currently installed.)
Preparing to unpack .../ebusd-3.0_amd64_mqtt1.deb ...
Unpacking ebusd (3.0) over (3.2) ...
dpkg: error processing archive /home/stefaan/ebusd-3.0_amd64_mqtt1.deb (--unpack):
 trying to overwrite '/etc/ebusd/broadcast.csv', which is also in package ebusd-configuration 2.1.b143f39-en
dpkg-deb: error: subprocess paste was killed by signal (Broken pipe)
Instructions:
1. Edit /etc/default/ebusd if necessary
   (especially if your device is not /dev/ttyUSB0)
2. Start the daemon with 'systemctl start ebusd'
3. Check the log file /var/log/ebusd.log
4. Make the daemon autostart with 'systemctl enable ebusd'
Errors were encountered while processing:
 /home/stefaan/ebusd-3.0_amd64_mqtt1.deb
E: Sub-process /usr/bin/dpkg returned an error code (1)
stefaan@home:~$ ebusd -V
ebusd 3.2.v3.2
stefaanv commented 5 years ago

Hi, When I disconnect and reconnect the Ethernet koppler while ebusd is running is seems to find the device after all, but rapidly spits out poll-read log events. Log file below

2018-11-03 21:19:54.548 [bus error] unable to open 192.168.0.36:5000: ERR: generic I/O error
2018-11-03 21:19:54.548 [bus notice] device invalid
2018-11-03 21:20:02.575 [bus notice] re-opened 192.168.0.36:5000
2018-11-03 21:20:02.575 [bus notice] signal acquired
2018-11-03 21:20:05.263 [bus notice] new master 03, master count 2
2018-11-03 21:20:08.102 [bus notice] new master 10, master count 3
2018-11-03 21:20:08.170 [update notice] received unknown MS cmd: 1008b5110101 / 094646008040400000ff
2018-11-03 21:20:12.213 [update notice] received unknown MS cmd: 1008b5100900006cffffff04ff00 / 0101
2018-11-03 21:20:14.707 [bus notice] scan 08: ;Vaillant;BAI00;0414;7401
2018-11-03 21:20:14.707 [update notice] store 08 ident: done
2018-11-03 21:20:14.707 [update notice] sent scan-read scan.08  QQ=31: Vaillant;BAI00;0414;7401
2018-11-03 21:20:14.707 [bus notice] scan 08: ;Vaillant;BAI00;0414;7401
2018-11-03 21:20:15.084 [main notice] read common config file vaillant/scan.csv
2018-11-03 21:20:16.143 [main notice] read common config file vaillant/general.csv
2018-11-03 21:20:16.205 [main notice] read common config file vaillant/broadcast.csv
2018-11-03 21:20:16.275 [main notice] read scan config file vaillant/08.bai.csv for ID "bai00", SW0414, HW7401
2018-11-03 21:20:16.435 [update notice] sent scan-read scan.08 id QQ=31:
2018-11-03 21:20:16.637 [update notice] sent scan-read scan.08 id QQ=31:
2018-11-03 21:20:16.837 [update notice] sent scan-read scan.08 id QQ=31:
2018-11-03 21:20:17.034 [update notice] sent scan-read scan.08 id QQ=31: 21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:17.266 [main notice] found messages: 210 (3 conditional on 25 conditions, 0 poll, 9 update)
2018-11-03 21:20:17.414 [update notice] sent scan-read scan.08 id QQ=31: 21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:17.611 [update notice] sent scan-read scan.08 id QQ=31: 21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:17.809 [update notice] sent scan-read scan.08 id QQ=31: 21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:18.006 [update notice] sent scan-read scan.08 id QQ=31: 21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:18.006 [bus notice] scan 08: ;21;07;15;0010002512;0001;006738;N6
2018-11-03 21:20:18.204 [update notice] received read bai Status01 QQ=10: 35.0;35.0;-;32.0;32.0;off
2018-11-03 21:20:20.145 [bus notice] scan 15: ;Vaillant;F4700;0114;6102
2018-11-03 21:20:20.145 [update notice] store 15 ident: done
2018-11-03 21:20:20.145 [update notice] sent scan-read scan.15  QQ=31: Vaillant;F4700;0114;6102
2018-11-03 21:20:20.145 [bus notice] scan 15: ;Vaillant;F4700;0114;6102
2018-11-03 21:20:20.331 [update notice] sent unknown MS cmd: 3115b5090124 / 09003231313333373030
2018-11-03 21:20:20.518 [update notice] sent scan-read scan.15 id QQ=31:
2018-11-03 21:20:20.709 [update notice] sent scan-read scan.15 id QQ=31:
2018-11-03 21:20:20.897 [update notice] sent scan-read scan.15 id QQ=31: 21;13;37;0020108135;0082;009419;N0
2018-11-03 21:20:20.898 [bus notice] scan 15: ;21;13;37;0020108135;0082;009419;N0
2018-11-03 21:20:21.042 [bus notice] max. symbols per second: 112
2018-11-03 21:20:21.090 [main notice] read scan config file vaillant/15.f47.csv for ID "f4700", SW0114, HW6102
2018-11-03 21:20:21.299 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:21.392 [main notice] found messages: 433 (3 conditional on 25 conditions, 1 poll, 9 update)
2018-11-03 21:20:21.473 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:21.649 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:21.824 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:21.998 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:22.175 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:22.410 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:22.582 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:22.756 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:22.929 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:23.104 [update notice] sent poll-read f47 YieldThisYear QQ=31:
2018-11-03 21:20:23.286 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:27.162 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:27.338 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:27.512 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:27.684 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:27.856 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:28.029 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:28.830 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:29.004 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:29.237 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:29.411 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:29.584 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:29.757 [update notice] sent poll-read f47 YieldThisYear QQ=31: 0;0;0;0;0;0;0;0;0;0;0;0
2018-11-03 21:20:30.150 [update notice] received update-read broadcast vdatetime QQ=10: 21:20:30;03.11.2018
2018-11-03 21:20:30.405 [update notice] received unknown MS cmd: 1008b512020064 / 00
2018-11-03 21:20:32.235 [update notice] received update-write bai SetMode QQ=10: auto;54.0;-;-;0;0;1;0;0;0
andig commented 5 years ago

Instead ofdowngrading you could try the docker image. As you‘re using tcp- does latency make a change for you?

john30 commented 5 years ago

for the downgrade: since you've also installed the ebusd-configuration package forced ("-f"), you consequently also have to install ebusd in the same way (using "-f"). I can only assume that another ebusd instance was still running when you did the downgrade. just check your process list

stefaanv commented 5 years ago

Solved. I uninstalled and purged both ebusd-configuration and ebusd packages, rebooted the machine and reinstalled ebusd v3.2 Now setting to the Hc1OPMode parameter also works. Thanks for the help !