Ich betreibe den PBM an einem Debian 10 Server. Mir ist durchaus klar, dass der PBM nicht die Ursache des Problems sein muss, aber er meldet nun halt mal einen Fehler, deshalb muss ich dort anfangen mit dem Debuggen.
Problem taucht täglich auf:
Code: Alles auswählen
rd@home:~$ journalctl -u owfs.service
-- Logs begin at Mon 2020-10-12 01:35:01 CEST, end at Mon 2020-10-12 08:11:17 CEST. --
Oct 12 06:25:05 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:25:10 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:25:10 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:25:16 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:25:21 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:25:21 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:25:21 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:25:47 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:25:53 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:26:19 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:26:19 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:26:24 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:26:24 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:26:55 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:27:13 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:27:13 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:27:18 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:27:18 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:27:23 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:27:23 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:27:24 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:27:42 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:27:42 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:28:44 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:28:50 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:28:50 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:30:06 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:30:06 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:30:11 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:30:11 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:30:16 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:30:17 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:31:14 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:31:19 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:31:20 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:32:37 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:32:37 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:32:47 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:32:47 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:32:48 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:32:59 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:32:59 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(2) bus master!
Oct 12 06:33:40 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:33:45 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:33:45 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(1) bus master!
Oct 12 06:33:54 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:33:54 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:34:10 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:34:15 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:34:15 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:34:26 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:34:26 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:34:59 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:35:21 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:35:37 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(1) bus master reconnected
Oct 12 06:35:42 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
Oct 12 06:35:47 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:35:47 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(0) bus master!
Oct 12 06:35:53 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(2) bus master reconnected
Oct 12 06:35:58 home OWFS[471]: DEFAULT: ow_elabnet.c:(231) PBM: detection error
Oct 12 06:35:58 home OWFS[471]: DEFAULT: ow_reconnect.c:(69) Failed to reconnect PBM(2) bus master!
rd@home:~$
Ich habe mal im systemd journal nachgeschaut, das Einzige was ich halbwegs
Code: Alles auswählen
Oct 12 06:20:01 home CRON[2980]: (rd) CMD (/home/rd/git.nobackup/Fronius_PVOutput_Uploader/update-fronius.sh)
Oct 12 06:20:01 home CRON[2964]: pam_unix(cron:session): session closed for user root
Oct 12 06:20:01 home CRON[2971]: pam_unix(cron:session): session closed for user sh
Oct 12 06:20:01 home CRON[2965]: pam_unix(cron:session): session closed for user rd
Oct 12 06:20:02 home CRON[2967]: pam_unix(cron:session): session closed for user rd
Oct 12 06:20:02 home CRON[2968]: pam_unix(cron:session): session closed for user rd
Oct 12 06:20:02 home CRON[2966]: pam_unix(cron:session): session closed for user rd
Oct 12 06:20:04 home CRON[2969]: pam_unix(cron:session): session closed for user rd
Oct 12 06:20:13 home CRON[2970]: pam_unix(cron:session): session closed for user rd
Oct 12 06:21:01 home CRON[6065]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:21:01 home CRON[6066]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:21:01 home CRON[6067]: (rd) CMD (PATH=/usr/local/bin:$PATH /home/sh/git/sh/bin/log-temp.sh >> /var/smarthome/logs/indoor-temp.log)
Oct 12 06:21:01 home CRON[6068]: (rd) CMD ((cd /var/smarthome/fronius; python3 ~/git.nobackup/fronius-json-tools/livedata2db.py))
Oct 12 06:21:04 home CRON[6066]: pam_unix(cron:session): session closed for user rd
Oct 12 06:21:16 home CRON[6065]: pam_unix(cron:session): session closed for user rd
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 available DHCP range: 192.168.7.2 -- 192.168.7.230
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 vendor class: dhcpcd-8.1.2:Linux-5.4.51-v7l+:armv7l:BCM2711
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 client provides name: raspberrypi
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 DHCPREQUEST(eth0.7) 192.168.7.50 dc:a6:32:4d:dc:db
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 tags: data, known, eth0.7
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 DHCPACK(eth0.7) 192.168.7.50 dc:a6:32:4d:dc:db raspi4
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 requested options: 1:netmask, 121:classless-static-route, 33:static-route,
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 requested options: 3:router, 6:dns-server, 12:hostname, 15:domain-name,
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 requested options: 26:mtu, 28:broadcast, 51:lease-time, 54:server-identifier,
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 requested options: 58:T1, 59:T2, 119:domain-search
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 next server: 192.168.7.1
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 1 option: 53 message-type 5
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 54 server-identifier 192.168.7.1
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 51 lease-time 12h
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 58 T1 5h37m29s
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 59 T2 10h7m29s
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 1 netmask 255.255.255.0
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 28 broadcast 192.168.7.255
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 3 router 192.168.7.1
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 4 option: 6 dns-server 192.168.7.1
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 11 option: 15 domain-name bokomoko.de
Oct 12 06:21:30 home dnsmasq-dhcp[3899]: 2191151236 sent size: 6 option: 12 hostname raspi4
Oct 12 06:22:01 home CRON[8045]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:22:01 home CRON[8044]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:22:01 home CRON[8047]: (rd) CMD (/home/rd/git.nobackup/Fronius_PVOutput_Uploader/update-fronius.sh)
Oct 12 06:22:01 home CRON[8046]: (rd) CMD ((cd /var/smarthome/fronius; python3 ~/git.nobackup/fronius-json-tools/livedata2db.py))
Oct 12 06:22:03 home CRON[8044]: pam_unix(cron:session): session closed for user rd
Oct 12 06:22:13 home CRON[8045]: pam_unix(cron:session): session closed for user rd
Oct 12 06:23:01 home CRON[10002]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:23:01 home CRON[10004]: (rd) CMD ((cd /var/smarthome/fronius; python3 ~/git.nobackup/fronius-json-tools/livedata2db.py))
Oct 12 06:23:04 home CRON[10002]: pam_unix(cron:session): session closed for user rd
Oct 12 06:24:01 home CRON[11921]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:24:01 home CRON[11922]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:24:01 home CRON[11924]: (rd) CMD ((cd /var/smarthome/fronius; python3 ~/git.nobackup/fronius-json-tools/livedata2db.py))
Oct 12 06:24:01 home CRON[11923]: (rd) CMD (/home/rd/git.nobackup/Fronius_PVOutput_Uploader/update-fronius.sh)
Oct 12 06:24:03 home CRON[11921]: pam_unix(cron:session): session closed for user rd
Oct 12 06:24:13 home CRON[11922]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:01 home CRON[13793]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 12 06:25:01 home CRON[13791]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 12 06:25:01 home CRON[13801]: (root) CMD (PATH=$PATH:/usr/sbin /usr/local/bin/watch-sip.sh)
Oct 12 06:25:01 home CRON[13800]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily ))
Oct 12 06:25:01 home CRON[13797]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:25:01 home CRON[13796]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:25:01 home CRON[13795]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:25:01 home CRON[13803]: (rd) CMD (PATH=/usr/local/bin:$PATH update-zehnder.sh)
Oct 12 06:25:01 home CRON[13799]: pam_unix(cron:session): session opened for user sh by (uid=0)
Oct 12 06:25:01 home CRON[13804]: (rd) CMD (PATH=/usr/local/bin:$PATH pub_outTemp.sh)
Oct 12 06:25:01 home CRON[13802]: (rd) CMD (/usr/local/bin/log-mtec.py >> /var/smarthome/logs/mtec.log)
Oct 12 06:25:01 home CRON[13794]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:25:01 home CRON[13806]: (sh) CMD (/usr/local/bin/alloff.sh)
Oct 12 06:25:01 home CRON[13807]: (rd) CMD (/usr/local/bin/presence2mqtt.sh)
Oct 12 06:25:01 home CRON[13798]: pam_unix(cron:session): session opened for user rd by (uid=0)
Oct 12 06:25:01 home CRON[13813]: (rd) CMD ((cd /var/smarthome/fronius; python3 ~/git.nobackup/fronius-json-tools/livedata2db.py))
Oct 12 06:25:01 home CRON[13792]: pam_unix(cron:session): session opened for user root by (uid=0)
Oct 12 06:25:01 home CRON[13817]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
Oct 12 06:25:01 home CRON[13792]: pam_unix(cron:session): session closed for user root
Oct 12 06:25:01 home CRON[13793]: pam_unix(cron:session): session closed for user root
Oct 12 06:25:01 home CRON[13799]: pam_unix(cron:session): session closed for user sh
Oct 12 06:25:02 home CRON[13794]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:02 home CRON[13796]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:02 home CRON[13795]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:02 home CRON[13797]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:05 home CRON[13798]: pam_unix(cron:session): session closed for user rd
Oct 12 06:25:05 home OWFS[471]: DEFAULT: ow_reconnect.c:(71) PBM(0) bus master reconnected
das Einzige was mir auffällt, ist dass der anacron kurz vorher losläuft
Code: Alles auswählen
Oct 12 06:25:01 home CRON[13800]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily ))
allerdings finde ich darin zunächst auch nichts Verdächtiges, zumal alles unmodifizierte Debian Skripte sind
Code: Alles auswählen
rd@home:~$ ls -l /etc/cron.daily/
total 64
-rwxr-xr-x 1 root root 539 Jan 25 2017 apache2
-rwxr-xr-x 1 root root 1478 May 28 2019 apt-compat
-rwxr-xr-x 1 root root 314 Nov 8 2014 aptitude
-rwxr-xr-x 1 root root 355 Oct 17 2014 bsdmainutils
-rwxr-xr-x 1 root root 1187 Apr 19 2019 dpkg
-rwxr-xr-x 1 root root 205 Jul 18 2016 etckeeper
-rwxr-xr-x 1 root root 4128 Sep 27 2019 exim4-base
-rwxr-xr-x 1 root root 377 Aug 29 2018 logrotate
-rwxr-xr-x 1 root root 1123 Feb 10 2019 man-db
-rwxr-xr-x 1 root root 543 Nov 14 2018 mlocate
-rwxr-xr-x 1 root root 1403 Mar 21 2019 ntp
-rwxr-xr-x 1 root root 249 Nov 19 2015 passwd
-rwxr-xr-x 1 root root 4571 May 20 2018 popularity-contest
-rwxr-xr-x 1 root root 441 May 25 2017 sysstat
rd@home:~$
Womit ich wieder zurück bin beim Anfang: Woran stört sich owfs bzw. PBM?
Danke und Gruß
Rainer