Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Debian/Ubuntu basierte CCU

Moderator: Co-Administratoren

Antworten
deifel
Beiträge: 256
Registriert: 27.05.2014, 11:10
Hat sich bedankt: 7 Mal

Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deifel » 18.06.2019, 08:23

Hallo zusammen,

ich nutze seit einigen Wochen Debmatic auf Debian auf einer VM (Proxmox). Am Anfang lief das System einwandfrei ohne Ausfälle.
Seitdem ich das HB-RF-USB-TK mit dem neuen Funkmodul verwende, habe ich jedoch enorme Stabilitätsprobleme.
Das System läuft erstmal grundsätzlich normal, bis irgendwann aus unerklärlichen Gründen die BidCOS Meldung (Eine Komponente oder Gerät reagiert nicht mehr...) erscheint und in der Web-UI alle Geräte verschwunden sind bzw. sich nichts mehr steuern lässt.
Das ganze lässt sich dann mit einem Neustart der ganzen VM beheben und dann läuft alles wieder normal.

Neben dem HB-RF-USB-TK nutze ich noch 2 weitere LAN-Gateways. Das System schafft es keine 3 Tage. Meist ist nach ca. 1 oder 2 Tagen Schluss.
Der Duty Cycle ist dabei insgesamt bei allerhöchstens 20%. Alles ist auch auf dem neuesten Stand.
Ich habe bereits versucht, alles auf einer separaten, neuen VM zu installieren und nur ein Backup von Debmatic (über die UI erstellt) zu importieren.
Außerdem habe ich mittlerweile das HB-RF-USB-TK an einem atkiven USB-Port hängen, was leider auch keine Besserung brachte.
Das Leeren des Caches per Cronjob habe ich testweise auch deaktiviert, bringt aber auch keine Besserung.

Das Logfile habe ich am Anhang beigefügt. Ich selbst kann die Einträge aber leider nicht korrekt deuten.
Kann jemand anhand den Einträgen dort das Problem eingrenzen bzw. erkennen?

Vielen Dank für Eure Hilfe.

Frank
Dateianhänge
logfile.txt
(1.02 MiB) 67-mal heruntergeladen
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deimos » 18.06.2019, 09:07

Hi,

rein Anhand des Logs lässt sich leider nichts sagen. Ich sehe, dass da einige Male der HmServer neu gestartet wurde, aber aus dem Log lässt sich nicht erkennen, ob das von alleine passiert ist oder ob das die angesprochenen Restarts waren.

Interessant wäre es, wenn du bei einem Ausfall vor dem Neustart mal einige Daten ausließt:

Code: Alles auswählen

lsusb
dmesg | tail -100
ps aux
tail -100 /var/log/syslog
systemctl status debmatic-rfd
Was ist eigentlich ein aktiver USB Port? Meinst du einen aktiven USB Hub?

Viele Grüße
Alex
Nach oben
deifel
Beiträge: 256
Registriert: 27.05.2014, 11:10
Hat sich bedankt: 7 Mal

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deifel » 18.06.2019, 09:14

Hallo Alex,

vielen Dank für Deine Hilfe!
Sorry, natürlich meinte ich einen aktiven USB-HUB zur besseren Stromversorgung;) Dachte, dass sich dadurch evtl. ab und zu das HM-RF-USB stört und sich das System deshalb verabschiedet - war aber nicht die Ursache.
Die Neustarts im Log waren vermutlich die manuellen von mir. Zur Zeit läuft das System in der Regel nur einen Tag stabil-.
Ich werde beim nächsten Ausfall die von Dir genannten Befehle durchführen und die Ergebnisse mitteilen.
Hoffentlich sieht man dort dann mehr bzw. kommt man dem Problem auf die Spur.
Das nervt mittlerweile schon enorm.

LG
Frank
Nach oben
deifel
Beiträge: 256
Registriert: 27.05.2014, 11:10
Hat sich bedankt: 7 Mal

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deifel » 20.06.2019, 22:47

Hi Alex,

es ist mal wieder so weit... :(
Anbei die Ausgaben der Befehle in der Hoffnung, dass Du da eine Ursache erkennen kannst:

Code: Alles auswählen

root@debmatic:~# lsusb
Bus 002 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub
Bus 005 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 004 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 003 Device 002: ID 0403:6f70 Future Technology Devices International, Ltd
Bus 003 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub
Bus 001 Device 002: ID 0627:0001 Adomax Technology Co., Ltd
Bus 001 Device 001: ID 1d6b:0001 Linux Foundation 1.1 root hub

Code: Alles auswählen

root@debmatic:~# root@debmatic:~# dmesg | tail -100
[217498.764041] sh (6201): drop_caches: 1
[217558.779588] sh (6237): drop_caches: 1
[217618.795652] sh (6274): drop_caches: 1
[217678.811382] sh (6310): drop_caches: 1
[217738.826911] sh (6354): drop_caches: 1
[217798.839114] sh (6393): drop_caches: 1
[217858.854207] sh (6429): drop_caches: 1
[217918.878457] sh (6465): drop_caches: 1
[217978.906215] sh (6502): drop_caches: 1
[218038.923367] sh (6544): drop_caches: 1
[218098.939302] sh (6580): drop_caches: 1
[218158.955568] sh (6616): drop_caches: 1
[218218.988127] sh (6653): drop_caches: 1
[218279.006356] sh (6692): drop_caches: 1
[218339.021762] sh (6733): drop_caches: 1
[218399.036264] sh (6772): drop_caches: 1
[218459.051414] sh (6808): drop_caches: 1
[218519.066830] sh (6844): drop_caches: 1
[218579.081820] sh (6881): drop_caches: 1
[218639.095152] sh (6929): drop_caches: 1
[218699.111342] sh (6966): drop_caches: 1
[218759.127357] sh (7008): drop_caches: 1
[218819.145984] sh (7044): drop_caches: 1
[218879.161084] sh (7081): drop_caches: 1
[218939.198163] sh (7123): drop_caches: 1
[218999.212034] sh (7162): drop_caches: 1
[219059.239569] sh (7198): drop_caches: 1
[219119.266649] sh (7234): drop_caches: 1
[219179.293702] sh (7271): drop_caches: 1
[219239.307514] sh (7315): drop_caches: 1
[219299.335594] sh (7348): drop_caches: 1
[219359.360868] sh (7384): drop_caches: 1
[219419.387249] sh (7420): drop_caches: 1
[219479.399585] sh (7456): drop_caches: 1
[219539.425660] sh (7501): drop_caches: 1
[219599.440304] sh (7540): drop_caches: 1
[219659.466019] sh (7579): drop_caches: 1
[219719.493074] sh (7616): drop_caches: 1

Code: Alles auswählen

root@debmatic:~# ps aux
USER       PID %CPU %MEM    VSZ   RSS TTY      STAT START   TIME COMMAND
root         1  0.0  0.2  56964  6680 ?        Ss   Jun18   0:02 /sbin/init
root         2  0.0  0.0      0     0 ?        S    Jun18   0:00 [kthreadd]
root         3  0.0  0.0      0     0 ?        S    Jun18   0:04 [ksoftirqd/0]
root         5  0.0  0.0      0     0 ?        S<   Jun18   0:00 [kworker/0:0H]
root         6  0.0  0.0      0     0 ?        S    Jun18   0:01 [kworker/u2:0]
root         7  0.0  0.0      0     0 ?        S    Jun18   0:09 [rcu_sched]
root         8  0.0  0.0      0     0 ?        S    Jun18   0:00 [rcu_bh]
root         9  0.0  0.0      0     0 ?        S    Jun18   0:00 [migration/0]
root        10  0.0  0.0      0     0 ?        S<   Jun18   0:00 [lru-add-drain]
root        11  0.0  0.0      0     0 ?        S    Jun18   0:00 [watchdog/0]
root        12  0.0  0.0      0     0 ?        S    Jun18   0:00 [cpuhp/0]
root        13  0.0  0.0      0     0 ?        S    Jun18   0:00 [kdevtmpfs]
root        14  0.0  0.0      0     0 ?        S<   Jun18   0:00 [netns]
root        15  0.0  0.0      0     0 ?        S    Jun18   0:00 [khungtaskd]
root        16  0.0  0.0      0     0 ?        S    Jun18   0:00 [oom_reaper]
root        17  0.0  0.0      0     0 ?        S<   Jun18   0:00 [writeback]
root        18  0.0  0.0      0     0 ?        S    Jun18   0:00 [kcompactd0]
root        19  0.0  0.0      0     0 ?        SN   Jun18   0:00 [ksmd]
root        21  0.0  0.0      0     0 ?        SN   Jun18   0:00 [khugepaged]
root        22  0.0  0.0      0     0 ?        S<   Jun18   0:00 [crypto]
root        23  0.0  0.0      0     0 ?        S<   Jun18   0:00 [kintegrityd]
root        24  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        25  0.0  0.0      0     0 ?        S<   Jun18   0:00 [kblockd]
root        26  0.0  0.0      0     0 ?        S<   Jun18   0:00 [devfreq_wq]
root        27  0.0  0.0      0     0 ?        S<   Jun18   0:00 [watchdogd]
root        28  0.0  0.0      0     0 ?        S    Jun18   0:00 [kswapd0]
root        29  0.0  0.0      0     0 ?        S<   Jun18   0:00 [vmstat]
root        41  0.0  0.0      0     0 ?        S<   Jun18   0:00 [kthrotld]
root        42  0.0  0.0      0     0 ?        S<   Jun18   0:00 [ipv6_addrconf]
root        75  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        76  0.0  0.0      0     0 ?        S<   Jun18   0:00 [ata_sff]
root        77  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        78  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        79  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        80  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        81  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        82  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        83  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        84  0.0  0.0      0     0 ?        S    Jun18   0:01 [kworker/u2:1]
root        85  0.0  0.0      0     0 ?        S    Jun18   0:00 [scsi_eh_0]
root        86  0.0  0.0      0     0 ?        S<   Jun18   0:00 [scsi_tmf_0]
root        87  0.0  0.0      0     0 ?        S    Jun18   0:00 [scsi_eh_1]
root        88  0.0  0.0      0     0 ?        S<   Jun18   0:00 [scsi_tmf_1]
root        91  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root        93  0.0  0.0      0     0 ?        S    Jun18   0:00 [scsi_eh_2]
root        94  0.0  0.0      0     0 ?        S<   Jun18   0:00 [scsi_tmf_2]
root        96  0.0  0.0      0     0 ?        S<   Jun18   0:00 [bioset]
root       354  0.0  0.0      0     0 ?        S<   Jun18   0:02 [kworker/0:1H]
root       387  0.0  0.0      0     0 ?        S<   Jun18   0:00 [kworker/u3:0]
root       394  0.0  0.0      0     0 ?        S    Jun18   0:03 [jbd2/sda1-8]
root       395  0.0  0.0      0     0 ?        S<   Jun18   0:00 [ext4-rsv-conve
root       416  0.2  0.3  61824  9728 ?        Ss   Jun18   8:26 /lib/systemd/sy
root       427  0.0  0.0      0     0 ?        S    Jun18   0:00 [kauditd]
root       449  0.0  0.1  45956  3936 ?        Ss   Jun18   0:45 /lib/systemd/sy
root       515  0.0  0.0      0     0 ?        S<   Jun18   0:00 [ttm_swap]
systemd+   609  0.0  0.1 127284  4092 ?        Ssl  Jun18   0:00 /lib/systemd/sy
root       621  0.0  0.1 250112  4424 ?        Ssl  Jun18   2:15 /usr/sbin/rsysl
root       625  0.0  0.0  29636  2680 ?        Ss   Jun18   0:00 /usr/sbin/cron
root       626  0.0  0.1  46472  4524 ?        Ss   Jun18   0:00 /lib/systemd/sy
message+   627  0.0  0.1  45112  3616 ?        Ss   Jun18   0:00 /usr/bin/dbus-d
root       693  0.0  0.0  14524  1492 tty1     Ss+  Jun18   0:00 /sbin/agetty --
root       702  0.0  0.1  69952  5272 ?        Ss   Jun18   0:00 /usr/sbin/sshd
root       711  0.0  0.0  37672  2404 ?        Ss   Jun18   0:00 /lib/nut/upsmon
nut        713  0.0  0.1  48756  3568 ?        S    Jun18   0:03 /lib/nut/upsmon
root       714  0.0  0.0  20472  2776 ?        Ss   Jun18   0:00 /sbin/dhclient
www-data   735  0.0  0.2  63028  7436 ?        Ss   Jun18   0:20 /usr/sbin/light
root       920  0.0  0.0   4480  1396 ?        Ss   Jun18   0:00 /bin/eq3configd
root       978  0.0  0.0   4684  1508 ?        Ss   Jun18   0:24 /bin/hss_led -l
root       992  0.0  0.0   3676  2232 ?        Ss   Jun18   0:42 /usr/local/addo
root       994  0.5  0.0   7244  2924 ?        Ssl  Jun18  21:33 /bin/multimacd
root      1017  0.0  0.3  61600  9924 ?        S    Jun18   0:13 /usr/sbin/light
root      1018  0.0  0.1   4952  3452 ?        S    Jun18   0:00 /bin/tclsh /usr
root      1023  0.0  0.0   4492  2336 ?        Ss   Jun18   0:00 /bin/hs485dLoad
root      1025  0.0  0.1  43140  5116 ?        Sl   Jun18   0:09 /bin/hs485d -l
root      1134  1.2  5.6 2133608 170456 ?      Sl   Jun18  48:12 /usr/bin/java -
root      1261  0.6  0.8  34948 26392 ?        Sl   Jun18  23:38 /bin/ReGaHss.no
root      9246  0.0  0.0      0     0 ?        S    22:24   0:00 [kworker/0:1]
root      9439  0.0  0.0      0     0 ?        S    22:29   0:00 [kworker/0:2]
root      9621  0.0  0.2  95208  6776 ?        Ss   22:33   0:00 sshd: root@pts/
root      9624  0.0  0.1  56392  5804 ?        Ss   22:33   0:00 /lib/systemd/sy
root      9625  0.0  0.0  82488  1624 ?        S    22:33   0:00 (sd-pam)
root      9632  0.0  0.1  21056  4780 pts/0    Ss   22:33   0:00 -bash
root      9733  0.0  0.0      0     0 ?        S    22:35   0:00 [kworker/0:0]
root      9748  0.0  0.1  38304  3128 pts/0    R+   22:35   0:00 ps aux
root@debmatic:~#

Code: Alles auswählen

root@debmatic:~# tail -100 /var/log/syslog
Jun 20 22:33:46 debmatic systemd[1]: Starting User Manager for UID 0...
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent (access for web browsers).
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent and passphrase cache.
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Paths.
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Sockets.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Timers.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Basic System.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Default.
Jun 20 22:33:47 debmatic systemd[9624]: Startup finished in 13ms.
Jun 20 22:33:47 debmatic systemd[1]: Started User Manager for UID 0.
Jun 20 22:34:01 debmatic CRON[9664]: (root) CMD (echo 1 > /proc/sys/vm/drop_caches)
Jun 20 22:34:01 debmatic kernel: [222839.488439] sh (9664): drop_caches: 1
Jun 20 22:34:02 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172180:1","STATE",false}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ0172180:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0172180:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1526804:1","STATE",false}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"NEQ1526804:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1526804:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172300:1","STATE",false}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ0172300:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0172300:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1501164:1","STATE",false}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"NEQ1501164:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1501164:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ1551504:1","STATE",false}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ1551504:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ1551504:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:01 debmatic CRON[9729]: (root) CMD (echo 1 > /proc/sys/vm/drop_caches)
Jun 20 22:35:01 debmatic kernel: [222899.513081] sh (9729): drop_caches: 1
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172180:1","INHIBIT",true}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ0172180:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0172180:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1526804:1","INHIBIT",true}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"NEQ1526804:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1526804:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172300:1","INHIBIT",true}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ0172300:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0172300:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1501164:1","INHIBIT",true}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"NEQ1501164:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1501164:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ1551504:1","INHIBIT",true}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {"OEQ1551504:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ1551504:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:05 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:36:01 debmatic CRON[9784]: (root) CMD (echo 1 > /proc/sys/vm/drop_caches)
Jun 20 22:36:01 debmatic kernel: [222959.538033] sh (9784): drop_caches: 1
root@debmatic:~#

Code: Alles auswählen

root@debmatic:~# tail -100 /var/log/syslog
Jun 20 22:33:46 debmatic systemd[1]: Starting User Manager for UID 0...
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent (                                            access for web browsers).
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent a                                            nd passphrase cache.
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent (                                            ssh-agent emulation).
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Paths.
Jun 20 22:33:47 debmatic systemd[9624]: Listening on GnuPG cryptographic agent a                                            nd passphrase cache (restricted).
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Sockets.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Timers.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Basic System.
Jun 20 22:33:47 debmatic systemd[9624]: Reached target Default.
Jun 20 22:33:47 debmatic systemd[9624]: Startup finished in 13ms.
Jun 20 22:33:47 debmatic systemd[1]: Started User Manager for UID 0.
Jun 20 22:34:01 debmatic CRON[9664]: (root) CMD (echo 1 > /proc/sys/vm/drop_cach                                            es)
Jun 20 22:34:01 debmatic kernel: [222839.488439] sh (9664): drop_caches: 1
Jun 20 22:34:02 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:02 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:34:18 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172180:1","STATE",fal                                            se}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ0172180:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1                                            505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0                                            172180:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1526804:1","STATE",fal                                            se}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"NEQ1526804:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1                                            505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1                                            526804:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172300:1","STATE",fal                                            se}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ0172300:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1                                            505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0                                            172300:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1501164:1","STATE",fal                                            se}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"NEQ1501164:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1                                            505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1                                            501164:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:00 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ1551504:1","STATE",fal                                            se}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ1551504:1","STATE",false}) [CallSetValue():iseXmlRpc.cpp:1                                            505]
Jun 20 22:35:00 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ1                                            551504:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:01 debmatic CRON[9729]: (root) CMD (echo 1 > /proc/sys/vm/drop_cach                                            es)
Jun 20 22:35:01 debmatic kernel: [222899.513081] sh (9729): drop_caches: 1
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172180:1","INHIBIT",t                                            rue}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ0172180:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:                                            1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0                                            172180:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1526804:1","INHIBIT",t                                            rue}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"NEQ1526804:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:                                            1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1                                            526804:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ0172300:1","INHIBIT",t                                            rue}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ0172300:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:                                            1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ0                                            172300:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"NEQ1501164:1","INHIBIT",t                                            rue}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"NEQ1501164:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:                                            1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = NEQ1                                            501164:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XmlRpc: Error in XmlRpcClient::writeReq                                            uest: write error (error 111). [error():iseXmlRpc.h:281]
Jun 20 22:35:02 debmatic ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.execute(                                            ) failed (url: xmlrpc_bin://127.0.0.1:32001, params: {"OEQ1551504:1","INHIBIT",t                                            rue}, result: nil) [CallXmlrpcMethod():iseXmlRpc.cpp:2602]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: XMLRPC 'setValue' call failed (interfac                                            e: 1007, params: {"OEQ1551504:1","INHIBIT",true}) [CallSetValue():iseXmlRpc.cpp:                                            1505]
Jun 20 22:35:02 debmatic ReGaHss: ERROR: rpc.CallSetValue failed; address = OEQ1                                            551504:1 [WriteValue():iseDOMdpHSS.cpp:76]
Jun 20 22:35:05 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:05 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:17 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: Event: MEQ0808088:21.VALUE=719.000000
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:33 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: Event: MEQ0808088:21.VALUE=718.000000
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send 1 events
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:35:49 debmatic hs485d: HSSXmlRpcEventDispatcher::Handle send completed
Jun 20 22:36:01 debmatic CRON[9784]: (root) CMD (echo 1 > /proc/sys/vm/drop_cach                                            es)
Jun 20 22:36:01 debmatic kernel: [222959.538033] sh (9784): drop_caches: 1
root@debmatic:~# ^C
root@debmatic:~# systemctl status debmatic-rfd
● debmatic-rfd.service - debmatic rfd
   Loaded: loaded (/lib/systemd/system/debmatic-rfd.service; enabled; vendor pre
   Active: failed (Result: core-dump) since Thu 2019-06-20 19:55:27 CEST; 2h 41m
  Process: 3467 ExecStopPost=/bin/rm -f /var/run/rfd.pid (code=exited, status=0/
  Process: 1070 ExecStart=/usr/share/debmatic/bin/start_rfd.sh (code=exited, sta
 Main PID: 1080 (code=dumped, signal=SEGV)

Jun 20 19:55:24 debmatic rfd[1080]: (OEQ0016319) CCU2CommController::sendBidcosR
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: Device 0x571FCE is now associated with inter
                                      CNT=7,RPTEN=1,RPTED=1,BIDI=1,BURST=0,WAKEU
                                      CHANNEL = 1
                                      STATUS = 206
                                      STATE = 0
                                      CLOCK = 0
                                      LOWBAT = 0
                                      DUTY_CYCLE = 0
                                      RSSI = 0
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Unable to find
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Device not foun
Jun 20 19:55:26 debmatic systemd[1]: debmatic-rfd.service: Main process exited,
lines 1-23
root@debmatic:~# systemctl status debmatic-rfd
● debmatic-rfd.service - debmatic rfd
   Loaded: loaded (/lib/systemd/system/debmatic-rfd.service; enabled; vendor pre
   Active: failed (Result: core-dump) since Thu 2019-06-20 19:55:27 CEST; 2h 42m
  Process: 3467 ExecStopPost=/bin/rm -f /var/run/rfd.pid (code=exited, status=0/
  Process: 1070 ExecStart=/usr/share/debmatic/bin/start_rfd.sh (code=exited, sta
 Main PID: 1080 (code=dumped, signal=SEGV)

Jun 20 19:55:24 debmatic rfd[1080]: (OEQ0016319) CCU2CommController::sendBidcosR
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: Device 0x571FCE is now associated with inter
                                      CNT=7,RPTEN=1,RPTED=1,BIDI=1,BURST=0,WAKEU
                                      CHANNEL = 1
                                      STATUS = 206
                                      STATE = 0
                                      CLOCK = 0
                                      LOWBAT = 0
                                      DUTY_CYCLE = 0
                                      RSSI = 0
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Unable to find
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Device not foun
Jun 20 19:55:26 debmatic systemd[1]: debmatic-rfd.service: Main process exited,
Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Unit entered failed s
Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Failed with result 'c
lines 3-25/25 (END)
root@debmatic:~# Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Unable to find
-bash: Syntaxfehler beim unerwarteten Wort `('
root@debmatic:~# Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Device not foun
-bash: Syntaxfehler beim unerwarteten Wort `('
root@debmatic:~# Jun 20 19:55:26 debmatic systemd[1]: debmatic-rfd.service: Main process exited,
Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Failed with result 'c-bash: Jun: Kommando nicht gefunden.
root@debmatic:~# Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Unit entered failed s
-bash: Jun: Kommando nicht gefunden.
root@debmatic:~# Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Failed with result 'csystemctl status debmatic-rfdsystemctl status debmatic-rfd
> systemctl status debmatic-rfd
>
>
>
> ^C
root@debmatic:~# systemctl status debmatic-rfd
● debmatic-rfd.service - debmatic rfd
   Loaded: loaded (/lib/systemd/system/debmatic-rfd.service; enabled; vendor preset: enabled)
   Active: failed (Result: core-dump) since Thu 2019-06-20 19:55:27 CEST; 2h 43min ago
  Process: 3467 ExecStopPost=/bin/rm -f /var/run/rfd.pid (code=exited, status=0/SUCCESS)
  Process: 1070 ExecStart=/usr/share/debmatic/bin/start_rfd.sh (code=exited, status=0/SUCCESS)
 Main PID: 1080 (code=dumped, signal=SEGV)

Jun 20 19:55:24 debmatic rfd[1080]: (OEQ0016319) CCU2CommController::sendBidcosRequest(): Got response after retry number 1
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: (PEQ1948616) Response status: OK, Data.
Jun 20 19:55:24 debmatic rfd[1080]: Device 0x571FCE is now associated with interface PEQ1948616 because of  @1980195170 RSSI
                                      CNT=7,RPTEN=1,RPTED=1,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=1,BCAST=1,TYPE=0x10
                                      CHANNEL = 1
                                      STATUS = 206
                                      STATE = 0
                                      CLOCK = 0
                                      LOWBAT = 0
                                      DUTY_CYCLE = 0
                                      RSSI = 0
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Unable to find device with serial PEQ0140497.
Jun 20 19:55:26 debmatic rfd[1080]: LGWPortWrapper::reconnect(): Device not found retrying in 20 seconds.
Jun 20 19:55:26 debmatic systemd[1]: debmatic-rfd.service: Main process exited, code=dumped, status=11/SEGV
Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Unit entered failed state.
Jun 20 19:55:27 debmatic systemd[1]: debmatic-rfd.service: Failed with result 'core-dump'.
Vielen Dank im Voraus für Deine Hilfe!

Grüße
Frank
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deimos » 21.06.2019, 06:51

Hi,

alle 60 Sekunden drop_caches ist etwas extrem, aber das wird nicht die Ursache sein, das müllt einfach nur das Log voll, was leider dafür sorgt, dass die Einschränkung auf 100 Zeilen in den Befehlen von mir den eigentlichen Zeitpunkt des Absturzes nicht mehr snzeigt.

Was man aber sehen kann: Der rfd verliert die Verbindung zu einem LAN GW (PEQ0140497) und kann diese nicht wieder aufbauen. Innrhalb des rfd scheint dann irgendein Bug zu sein, so dass er da keinen korrekten Reconnect macht, sondern mit einer SEGV abschmiert. SEGV bedeutet generell, dass da eine falsche Sprungmarke angesteuert werden soll. In 99% ein Bug, aber theoretisch kann das auch ein kaputter Arbeitsspeicher sein.
Da das ganze im Closed Source von eQ-3 ist, kann ich an der Stelle aber leider nicht tiefer reinschauen.

Viele Grüße
Alex
Nach oben
deifel
Beiträge: 256
Registriert: 27.05.2014, 11:10
Hat sich bedankt: 7 Mal

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deifel » 21.06.2019, 07:23

Moin Alex und danke für die Auswertung.

Den Cronjob habe ich normalerweise stündlich laufen. Habe es jetzt wieder geändert.
Und mein Problem werde ich dann wohl nicht so einfach zu lösen bekommen wenn ich Dich richtig verstehe...
Hast Du mir einen Vorschlag, was ich machen könnte? Soll ich testweise einfach mal das betroffene LAN-.GW komplett raus nehmen und testen, ob es dann besser wird? Oder Dein USB-Modul wieder rausnehmen (habe noch keine IP-Geräte im Einsatz)?

Viele Grüße
Frank
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deimos » 21.06.2019, 08:27

Hi,

wenn du ohne das LGW auskommst, dann würde ich es mal ohne dieses ausprobieren.

Viele Grüße
Alex
Nach oben
deifel
Beiträge: 256
Registriert: 27.05.2014, 11:10
Hat sich bedankt: 7 Mal

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von deifel » 27.06.2019, 09:02

Kurze Rückmeldung zu dieser Sache:

Seit ich das betroffene LAN-GW aus dem Netz habe, ist kein weiterer Fehler aufgetreten und Debmatic läuft stabil.
Grundsätzlich sehr ärgerlich, da ich so wie es aussieht ein zusätzliches LAN-GW benötige.
Ich werde mal versuchen, ein anderes zu testen um auszuschließen, dass das eine Gerät einen einen "Schuss" hat.

Viele Grüße
Nach oben
Buzz2912
Beiträge: 61
Registriert: 14.10.2012, 12:59

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von Buzz2912 » 27.06.2019, 17:08

Ich benutze auch das USB Modul mit dem neuen Funkmodul unter Proxmox mit einer Ubuntu 18.04 VM. Ich habe auch ein Funk Lan GW. Den neueren eckigen.
Bei mir läuft alles perfekt stabil.
Genauso unter Raspbian 9 direkt.

Gruß Sebastian
Nach oben
Benutzeravatar
eiGelbGeek
Beiträge: 979
Registriert: 24.07.2014, 17:46
Wohnort: Ruhrpottrandgebiet
Hat sich bedankt: 105 Mal
Danksagung erhalten: 19 Mal

Re: Ständige Ausfälle von Debmatic mit HB-RF-USB-TK

Beitrag von eiGelbGeek » 18.08.2019, 15:09

deifel hat geschrieben: ↑
27.06.2019, 09:02
 Kurze Rückmeldung zu dieser Sache:

Seit ich das betroffene LAN-GW aus dem Netz habe, ist kein weiterer Fehler aufgetreten und Debmatic läuft stabil.
Grundsätzlich sehr ärgerlich, da ich so wie es aussieht ein zusätzliches LAN-GW benötige.
Ich werde mal versuchen, ein anderes zu testen um auszuschließen, dass das eine Gerät einen einen "Schuss" hat.

Viele Grüße
Wo wird dein debmatic denn weiter verarbeitet? (openHAB vielleicht?) oder arbeitest du nur in der HM Welt?
Wenn deine komplette Logik z.b. in openhab liegt, dann ziehe einfach eine 2te CCU mit debmatic (z.b. auf einen Raspberry Pi) auf, anstatt ein LAN Gateway. (Die LAN Gateways haben schon immer und überall ärger gemacht)

Ich betreibe 2 x debmatic einmal auf einen RPi 4/4GB im Keller (dort läuft dann noch openHAB 2.5) und einen RPi 3 in der Wohnung, dort ist noch ein Conbee2 (Zigbee) und ein Z-Wave Stick dran (der Z-Wave Stick wird mit ser2net/socat zum RPi 4 / 4GB im Keller verbunden)

Wenn deine Logik auf der CCU liegt, dann ist eine 2te CCU natürlich suboptimal... weil die Geräte nicht untereinander kommunizieren können ;-)

Wenn du irgendwas anderes als openHAB verwendest, solltest du dich vorher schlau machen ob mehrfache CCU Instanzen unterstützt werden.
Nur weil es nicht geht, muss es nicht kaputt sein ^^

Apple for Work, Linux for Network, iOS for Mobility and still Windows for Solitaire
Nach oben
Antworten

Zurück zu „debmatic“