(gelöst) FHEM stürzt ab bei angeschlossenem USB Device

[Byllyy]

Ich habe ein Problem mit einem PI4 bei dem ein Arduino Mega über USB angeschlossen ist.

Wenn ich den Raspberry mit eingestecktem USB starte, verfällt er in eine Schleife und startet immer neu.
Ohne eingesteckten USB startet er fehlerfrei und ich kann den Arduino über USB dazustecken und das System läuft fehlerfrei.

Im Log sehe ich die Meldung "hash- or arrayref expected (not a simple scalar, use allow_nonref to allow this) at ./FHEM/37_echodevice.pm line 1682."
Ich bin leider zu unerfahren um damit eine Fehleranalyse zu finden.

Hier mein Log mit eingestecktem USB und dem Fehler:

Code Auswählen Erweitern

2020.01.29 13:18:02 1: Including fhem.cfg
2020.01.29 13:18:03 3: WEB: port 8083 opened
2020.01.29 13:18:03 2: eventTypes: loaded 1388 events from ./log/eventTypes.txt
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] Initialized version 4.3.020
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] HMCCU: Initializing device
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] HMCCU: Read 6 devices with 117 channels from CCU 192.168.188.96
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] HMCCU: Read 3 interfaces from CCU 192.168.188.96
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] HMCCU: Read 1 programs from CCU 192.168.188.96
2020.01.29 13:18:05 1: HMCCU: [RaspMatic : 586] HMCCU: Read 0 virtual groups from CCU 192.168.188.96
2020.01.29 13:18:06 1: HMCCURPCPROC: [d_rpc188096BidCos_RF : 586] Initialized version 1.9 for interface BidCos-RF with I/O device RaspMatic
2020.01.29 13:18:06 1: [Shelly_Attr] setting the mode attribute only works for model=shelly2|shelly2.5|shellyrgbw
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G070L80371760R05
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G090QU0674440GTA
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_be31f0fd6bb8477690f2f8abf747f47c
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G2A0RF0374350KP4
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_977c7ff4825d422a8cd4d5d6a7b0f8ac
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G090U608841711SV
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G090P30874530UAV
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G090LF1181750804
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G070RQ13843300WU
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_f774ea3bbb2a408294618139e79a49c6
2020.01.29 13:18:06 0: [echodevice] load ECHO Device ECHO_G090U61091861XLE
2020.01.29 13:18:06 1: Including ./log/fhem.save
2020.01.29 13:18:06 3: Opening Arduino device /dev/ttyACM0
2020.01.29 13:18:06 3: Setting Arduino serial parameters to 57600,8,N,1
2020.01.29 13:18:06 3: Arduino device opened
2020.01.29 13:18:06 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2020.01.29 13:18:06 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/33_readingsProxy.pm line 278.
2020.01.29 13:18:06 3: Shellykueche return value: Please define  first
2020.01.29 13:18:06 1: usb create starting
2020.01.29 13:18:06 3: Probing ZWDongle device /dev/serial1
2020.01.29 13:18:07 3: Probing CUL device /dev/ttyAMA0
2020.01.29 13:18:07 3: Probing TCM_ESP3 device /dev/ttyAMA0
2020.01.29 13:18:07 3: Probing ZWDongle device /dev/ttyAMA0
2020.01.29 13:18:07 3: Probing SIGNALDuino device /dev/ttyAMA0
2020.01.29 13:18:07 3: Probing MYSENSORS device /dev/ttyAMA0
2020.01.29 13:18:07 3: Probing ArduCounter device /dev/ttyAMA0
2020.01.29 13:18:08 3: Probing ElsnerWS device /dev/ttyAMA0
2020.01.29 13:18:09 3: Probing FRM device /dev/ttyAMA0
2020.01.29 13:18:14 1: usb create end
2020.01.29 13:18:14 0: Featurelevel: 6
2020.01.29 13:18:14 0: Server started with 119 defined entities (fhem.pl:21056/2020-01-26 perl:5.028001 os:linux user:fhem pid:586)
2020.01.29 13:18:14 3: Arduino querying Firmata versions
2020.01.29 13:18:14 3: Arduino Firmata Firmware Version: StandardFirmata.ino V_2_05 (using Protocol Version: V_2_05)
2020.01.29 13:18:14 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 4763.
2020.01.29 13:18:14 3: [Echodevice] [echodevice_setState] to connected
2020.01.29 13:18:14 2: ALARM: set push msg 'Achtung' 'Alarm im Haus!' '' 0 'alien': Unable to send message: Device is disabled
hash- or arrayref expected (not a simple scalar, use allow_nonref to allow this) at ./FHEM/37_echodevice.pm line 1682.
2020.01.29 13:18:15 1: Including fhem.cfg
2020.01.29 13:18:15 3: WEB: port 8083 opened
2020.01.29 13:18:15 2: eventTypes: loaded 1388 events from ./log/eventTypes.txt
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] Initialized version 4.3.020
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] HMCCU: Initializing device
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] HMCCU: Read 6 devices with 117 channels from CCU 192.168.188.96
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] HMCCU: Read 3 interfaces from CCU 192.168.188.96
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] HMCCU: Read 1 programs from CCU 192.168.188.96
2020.01.29 13:18:16 1: HMCCU: [RaspMatic : 719] HMCCU: Read 0 virtual groups from CCU 192.168.188.96
2020.01.29 13:18:17 1: HMCCURPCPROC: [d_rpc188096BidCos_RF : 719] Initialized version 1.9 for interface BidCos-RF with I/O device RaspMatic
2020.01.29 13:18:17 1: [Shelly_Attr] setting the mode attribute only works for model=shelly2|shelly2.5|shellyrgbw
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G070L80371760R05
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G090QU0674440GTA
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_be31f0fd6bb8477690f2f8abf747f47c
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G2A0RF0374350KP4
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_977c7ff4825d422a8cd4d5d6a7b0f8ac
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G090U608841711SV
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G090P30874530UAV
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G090LF1181750804
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G070RQ13843300WU
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_f774ea3bbb2a408294618139e79a49c6
2020.01.29 13:18:17 0: [echodevice] load ECHO Device ECHO_G090U61091861XLE
2020.01.29 13:18:17 1: Including ./log/fhem.save
2020.01.29 13:21:52 3: Opening Arduino device /dev/ttyACM0
2020.01.29 13:21:52 3: Setting Arduino serial parameters to 57600,8,N,1
2020.01.29 13:21:52 3: Arduino device opened
2020.01.29 13:21:52 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2020.01.29 13:21:52 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/33_readingsProxy.pm line 278.
2020.01.29 13:21:52 3: Shellykueche return value: Please define  first
2020.01.29 13:21:52 1: usb create starting
2020.01.29 13:21:52 3: Probing ZWDongle device /dev/serial1
2020.01.29 13:21:52 3: Probing CUL device /dev/ttyAMA0
2020.01.29 13:21:52 3: Probing TCM_ESP3 device /dev/ttyAMA0
2020.01.29 13:21:52 3: Probing ZWDongle device /dev/ttyAMA0
2020.01.29 13:21:52 3: Probing SIGNALDuino device /dev/ttyAMA0
2020.01.29 13:21:52 3: Probing MYSENSORS device /dev/ttyAMA0
2020.01.29 13:21:53 3: Probing ArduCounter device /dev/ttyAMA0
2020.01.29 13:21:53 3: Probing ElsnerWS device /dev/ttyAMA0
2020.01.29 13:21:54 3: Probing FRM device /dev/ttyAMA0
2020.01.29 13:21:59 1: usb create end
2020.01.29 13:21:59 0: Featurelevel: 6
2020.01.29 13:21:59 0: Server started with 119 defined entities (fhem.pl:21056/2020-01-26 perl:5.028001 os:linux user:fhem pid:719)
2020.01.29 13:21:59 3: Arduino querying Firmata versions
2020.01.29 13:21:59 3: Arduino Firmata Firmware Version: StandardFirmata.ino V_2_05 (using Protocol Version: V_2_05)
2020.01.29 13:21:59 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 4763.
2020.01.29 13:21:59 3: [Echodevice] [echodevice_setState] to connected
2020.01.29 13:22:00 2: ALARM: set push msg 'Achtung' 'Alarm im Haus!' '' 0 'alien': Unable to send message: Device is disabled
hash- or arrayref expected (not a simple scalar, use allow_nonref to allow this) at ./FHEM/37_echodevice.pm line 1682.
2020.01.29 13:22:00 1: Including fhem.cfg
2020.01.29 13:22:00 3: WEB: port 8083 opened
2020.01.29 13:22:00 2: eventTypes: loaded 1388 events from ./log/eventTypes.txt
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] Initialized version 4.3.020
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] HMCCU: Initializing device
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] HMCCU: Read 6 devices with 117 channels from CCU 192.168.188.96
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] HMCCU: Read 3 interfaces from CCU 192.168.188.96
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] HMCCU: Read 1 programs from CCU 192.168.188.96
2020.01.29 13:22:01 1: HMCCU: [RaspMatic : 724] HMCCU: Read 0 virtual groups from CCU 192.168.188.96
2020.01.29 13:22:02 1: HMCCURPCPROC: [d_rpc188096BidCos_RF : 724] Initialized version 1.9 for interface BidCos-RF with I/O device RaspMatic
2020.01.29 13:22:02 1: [Shelly_Attr] setting the mode attribute only works for model=shelly2|shelly2.5|shellyrgbw
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G070L80371760R05
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G090QU0674440GTA
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_be31f0fd6bb8477690f2f8abf747f47c
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G2A0RF0374350KP4
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_977c7ff4825d422a8cd4d5d6a7b0f8ac
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G090U608841711SV
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G090P30874530UAV
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G090LF1181750804
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G070RQ13843300WU
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_f774ea3bbb2a408294618139e79a49c6
2020.01.29 13:22:03 0: [echodevice] load ECHO Device ECHO_G090U61091861XLE
2020.01.29 13:22:03 1: Including ./log/fhem.save
2020.01.29 13:22:03 3: Opening Arduino device /dev/ttyACM0
2020.01.29 13:22:03 3: Setting Arduino serial parameters to 57600,8,N,1
2020.01.29 13:22:03 3: Arduino device opened
2020.01.29 13:22:03 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2020.01.29 13:22:03 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/33_readingsProxy.pm line 278.
2020.01.29 13:22:03 3: Shellykueche return value: Please define  first
2020.01.29 13:22:03 1: usb create starting
2020.01.29 13:22:03 3: Probing ZWDongle device /dev/serial1
2020.01.29 13:22:03 3: Probing CUL device /dev/ttyAMA0
2020.01.29 13:22:03 3: Probing TCM_ESP3 device /dev/ttyAMA0
2020.01.29 13:22:03 3: Probing ZWDongle device /dev/ttyAMA0
2020.01.29 13:22:03 3: Probing SIGNALDuino device /dev/ttyAMA0
2020.01.29 13:22:04 3: Probing MYSENSORS device /dev/ttyAMA0
2020.01.29 13:22:04 3: Probing ArduCounter device /dev/ttyAMA0
2020.01.29 13:22:04 3: Probing ElsnerWS device /dev/ttyAMA0
2020.01.29 13:22:05 3: Probing FRM device /dev/ttyAMA0
2020.01.29 13:22:11 1: usb create end
2020.01.29 13:22:11 0: Featurelevel: 6
2020.01.29 13:22:11 0: Server started with 119 defined entities (fhem.pl:21056/2020-01-26 perl:5.028001 os:linux user:fhem pid:724)
2020.01.29 13:22:11 3: Arduino querying Firmata versions
2020.01.29 13:22:11 3: Arduino Firmata Firmware Version: StandardFirmata.ino V_2_05 (using Protocol Version: V_2_05)
2020.01.29 13:22:11 3: [Echodevice] [echodevice_setState] to connected
2020.01.29 13:22:11 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 4763.
2020.01.29 13:22:11 2: ALARM: set push msg 'Achtung' 'Alarm im Haus!' '' 0 'alien': Unable to send message: Device is disabled
hash- or arrayref expected (not a simple scalar, use allow_nonref to allow this) at ./FHEM/37_echodevice.pm line 1682.
2020.01.29 13:22:12 1: Including fhem.cfg
2020.01.29 13:22:12 3: WEB: port 8083 opened
2020.01.29 13:22:12 2: eventTypes: loaded 1388 events from ./log/eventTypes.txt
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] Initialized version 4.3.020
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] HMCCU: Initializing device
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] HMCCU: Read 6 devices with 117 channels from CCU 192.168.188.96
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] HMCCU: Read 3 interfaces from CCU 192.168.188.96
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] HMCCU: Read 1 programs from CCU 192.168.188.96
2020.01.29 13:22:13 1: HMCCU: [RaspMatic : 731] HMCCU: Read 0 virtual groups from CCU 192.168.188.96
2020.01.29 13:22:14 1: HMCCURPCPROC: [d_rpc188096BidCos_RF : 731] Initialized version 1.9 for interface BidCos-RF with I/O device RaspMatic
2020.01.29 13:22:14 1: [Shelly_Attr] setting the mode attribute only works for model=shelly2|shelly2.5|shellyrgbw
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G070L80371760R05
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G090QU0674440GTA
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_be31f0fd6bb8477690f2f8abf747f47c
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G2A0RF0374350KP4
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_977c7ff4825d422a8cd4d5d6a7b0f8ac
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G090U608841711SV
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G090P30874530UAV
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G090LF1181750804
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G070RQ13843300WU
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_f774ea3bbb2a408294618139e79a49c6
2020.01.29 13:22:14 0: [echodevice] load ECHO Device ECHO_G090U61091861XLE
2020.01.29 13:22:14 1: Including ./log/fhem.save
2020.01.29 13:22:14 3: Opening Arduino device /dev/ttyACM0
2020.01.29 13:22:14 3: Setting Arduino serial parameters to 57600,8,N,1
2020.01.29 13:22:14 3: Arduino device opened
2020.01.29 13:22:14 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2020.01.29 13:22:14 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/33_readingsProxy.pm line 278.
2020.01.29 13:22:14 3: Shellykueche return value: Please define  first
2020.01.29 13:22:14 1: usb create starting
2020.01.29 13:22:15 3: Probing ZWDongle device /dev/serial1
2020.01.29 13:22:15 3: Probing CUL device /dev/ttyAMA0
2020.01.29 13:22:15 3: Probing TCM_ESP3 device /dev/ttyAMA0
2020.01.29 13:22:15 3: Probing ZWDongle device /dev/ttyAMA0
2020.01.29 13:22:15 3: Probing SIGNALDuino device /dev/ttyAMA0
2020.01.29 13:22:15 3: Probing MYSENSORS device /dev/ttyAMA0
2020.01.29 13:22:15 3: Probing ArduCounter device /dev/ttyAMA0
2020.01.29 13:22:16 3: Probing ElsnerWS device /dev/ttyAMA0
2020.01.29 13:22:17 3: Probing FRM device /dev/ttyAMA0
2020.01.29 13:22:22 1: usb create end
2020.01.29 13:22:22 0: Featurelevel: 6
2020.01.29 13:22:22 0: Server started with 119 defined entities (fhem.pl:21056/2020-01-26 perl:5.028001 os:linux user:fhem pid:731)
2020.01.29 13:22:22 3: Arduino querying Firmata versions
2020.01.29 13:22:22 1: /dev/ttyACM0 disconnected, waiting to reappear (Arduino)
2020.01.29 13:22:22 1: PERL WARNING: Use of uninitialized value $value in string eq at fhem.pl line 4763.

Dank für eure Unterstützung und LG,
Byllyy

[Beta-User]

Hmmm, das kann einiges und nichts sein...

Grundsätzlich sollte man auf einem Pi initialUsbCheck disablen oder löschen, das führt immer wieder zu Problemen, meistens hängt der Pi ewig rum (30 Min. sind keine Seltenheit). Hier würde ich annehmen, dass das OS denkt, dass FHEM nicht ordnungsgemäß startet und systemd das Beenden und Neustarten veranlaßt...

Andere, weniger wahrscheinliche Variante wäre Stromversorgung, gerade beim Systemstart ist mehr Power gefordert.

Weiterer Tipp (hat aber nichts mit dem Hänger hier zu tun): USB-Geräte immer "by-id" einbinden, wo es geht...

[Byllyy]

Hallo Beta-User,
danke für die schnelle Antwort.
Ich hatte die Stromversorgung eigentlich ausgeschlossen, da das Sysytem schon einige Wochen fehlerfrei gelaufen ist aber man weiß ja nie. Ich teste mal.
Glaubst du die Meldung "

Code Auswählen Erweitern

ash- or arrayref expected (not a simple scalar, use allow_nonref to allow this) at ./FHEM/37_echodevice.pm line 1682. hilft nicht weiter?

Ich teste mal deine Vorschläge, Danke schon einmal.
LG,
Byllyy

[Wzut]

ich würde an deiner Stelle mit der Fehlermeldung mal nach https://forum.fhem.de/index.php/topic,82631.0.html gehen,
da scheint noch mehr nicht zu gehen mit FHEM 6.0 .....

[Beta-User]

ich würde an deiner Stelle mit der Fehlermeldung mal nach https://forum.fhem.de/index.php/topic,82631.0.html gehen,
da scheint noch mehr nicht zu gehen mit FHEM 6.0 .....

Ok, das war mir jetzt neu...

Ansonsten:

Na ja, an sich gebe ich dir recht, dass diese Fehlermeldung schon so aussieht, als wäre das das Problem. Nur erklärt es eben nicht so einfach, warum es ohne das USB-Gerät geht, das hat doch mit dem echodevice nichts zu tun, oder?

Anders könnte es natürlich sein, wenn uninitialisierte Werte aus FIRMATA (der Arduino läuft doch mit dieser Firmware?) kommen, die irgendwie in dieser Alarm-Meldung verarbeitet werden. Aber dazu müßtest du einen Lappen liefern, damit ich die Glaskugel polieren kann .

[Byllyy]

Mit "disable initialUsbCheck" und anderem Netzteil ist der Fehler bisher nicht wieder aufgetaucht.

Vielen Dank für eure Hilfe