[gelöst] PRESENCE nach Backup nicht mehr ausführbar/Fehler in PERL Modulen?

r00t2 · 26 Juli 2020, 17:48:44

Hallo zusammen,

für gut über 110 Tage lief meine FHEM Installation sauber und problemlos durch.

Ich mache automatisiert jeden Sonntag früh um 03:03:03h ein FHEM-Backup. Das hat bisher auch super geklappt.

Doch heute Nacht lief wohl etwas schief, das Log sagt:

Code Auswählen

2020.07.26 03:03:03 1: Starting weekly backup job at sunday night.
2020.07.26 03:03:03 2: backup include: 
2020.07.26 03:03:03 2: backup include: 
2020.07.26 03:03:03 2: Backup with command: tar czf ./backup/FHEM-20200726_030303.tar.gz "./log-archive" "./demolog" "./fhem.cfg-backup-20170105-01" "./fhem.cfg-backup-20170129-01" "./contrib" "./fhem.pl" "./restoreDir" "./log" "./fhem.cfg.demo" "./README_DEMO.txt" "./fhem.cfg.org" "./FHEM" "./configDB.pm" "./fhem.cfg-backup-20161128-01" "./CHANGED" "./MAINTAINER.txt" "./unused" "./www" "./fhem.cfg-backup-20161130-01" "./fhem.cfg.bak" "./fhem.cfg" "./a" "./docs"
2020.07.26 03:03:03 3: backup : Started the backup in the background, watch the log for details
2020.07.26 03:03:03 3: time_DoWeeklyBackup_by_SundayNight: Started the backup in the background, watch the log for details
2020.07.26 03:03:42 2: PRESENCE (pres_MausTab) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.103"
2020.07.26 03:03:42 2: PRESENCE (pres_Phone_ALTEN) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.105"
2020.07.26 03:03:51 2: PRESENCE (pres_Mausophon) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.228"
2020.07.26 03:04:16 2: PRESENCE (pres_LED_Sofa) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.251"
2020.07.26 03:04:16 2: PRESENCE (pres_LoeweArt40) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.31"
2020.07.26 03:04:16 2: PRESENCE (pres_GoogleDNS) - error while processing check: Could not execute ping command: "ping -c 4 8.8.8.8"
2020.07.26 03:04:24 2: PRESENCE (pres_FritzBox7330) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.1"
2020.07.26 03:04:24 2: PRESENCE (pres_GhostWave) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.101"
2020.07.26 03:04:25 2: PRESENCE (pres_MausRadio) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.106"
2020.07.26 03:04:29 2: PRESENCE (pres_MausBook) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.201"
Backup done
2020.07.26 03:04:35 2: PRESENCE (pres_VSX_S510) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.33"
2020.07.26 03:04:51 2: PRESENCE (pres_Mausophon) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.228"
2020.07.26 03:05:12 2: PRESENCE (pres_MausTab) - error while processing check: Could not execute ping command: "ping -c 4 192.168.178.103"
...

Das geht so weiter und weiter. Scheinbar klappt da was mit dem PRESENCE Module nicht mehr.

Also habe ich erstmal ein shutdown restart gemacht.

Ergebnis waren noch mehr Fehler im Log:

Code Auswählen

2020.07.26 17:14:08 0: Server shutdown
2020.07.26 17:14:08 1: Shutdown executed
2020.07.26 17:14:10 1: Including fhem.cfg
2020.07.26 17:14:10 3: telnetPort: port 7072 opened
2020.07.26 17:14:11 3: WEB: port 8083 opened
2020.07.26 17:14:11 2: eventTypes: loaded 1428 events from ./log/eventTypes.txt
2020.07.26 17:14:11 3: Opening ZWDongle_0 device /dev/ttyACM0
2020.07.26 17:14:11 3: Setting ZWDongle_0 serial parameters to 115200,8,N,1
2020.07.26 17:14:12 3: ZWDongle_0 device opened
2020.07.26 17:14:13 3: Opening av_VSX_S510 device 192.168.178.33:8102
2020.07.26 17:14:14 1: reload: Error:Modul 98_TRAFFIC deactivated:
 Unrecognized character \xA0; marked by <-- HERE after 			   <-- HERE near column 7 at /usr/share/perl5/HTTP/Response.pm line 142, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.

2020.07.26 17:14:14 0: Unrecognized character \xA0; marked by <-- HERE after 			   <-- HERE near column 7 at /usr/share/perl5/HTTP/Response.pm line 142, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.

2020.07.26 17:14:14 1: reload: Error:Modul 98_TRAFFIC deactivated:
 Attempt to reload LWP/Simple.pm aborted.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.

2020.07.26 17:14:14 0: Attempt to reload LWP/Simple.pm aborted.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.

2020.07.26 17:14:14 3: Opening fb_FritzBox_CallMonitor device 192.168.178.1:1012
2020.07.26 17:14:14 3: WEBphone: port 8084 opened
2020.07.26 17:14:15 3: TABLETUI: new ext defined infix:ftui/: dir:./www/tablet:
2020.07.26 17:14:15 3: Registering HTTPSRV TABLETUI for URL /ftui   and assigned link ftui/ ...
2020.07.26 17:14:15 3: Read password from file uniqueID
2020.07.26 17:14:21 0: EGPM2LAN: Cant connect to 192.168.178.252
2020.07.26 17:14:25 3: HttpUtils: http://192.168.178.252/login.html: Can't connect(1) to http://192.168.178.252:80: IO::Socket::INET: connect: No route to host
2020.07.26 17:14:25 3: CustomGetFileFromURL http://192.168.178.252/login.html: Can't connect(1) to http://192.168.178.252:80: IO::Socket::INET: connect: No route to host
2020.07.26 17:14:26 3: [wtr_Twilight] got no weather info from yahoo. Error code: http://query.yahooapis.com/v1/public/yql?q=select%20*%20from%20weather.forecast%20where%20woeid=0%20and%20u=%27c%27&format=json&env=store%3A%2F%2Fdatatables.org%2Falltableswithkeys: Can't connect(1) to http://query.yahooapis.com:80: IO::Socket::INET: Bad hostname 'query.yahooapis.com:80'
2020.07.26 17:14:26 1: PERL WARNING: Bareword found where operator expected at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Unsubscribe.pm line 2, near "8dself"
2020.07.26 17:14:26 1: PERL WARNING: 	(Missing operator before dself?)
2020.07.26 17:14:26 1: PERL WARNING: Array found where operator expected at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Unsubscribe.pm line 2, near "p@_"
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::Unsubscribe : Unrecognized character \x10; marked by <-- HERE after  $self->SU<-- HERE near column 14 at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Unsubscribe.pm line 4, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::UnsubAck : Can't locate Net.pm in @INC (you may need to install the Net module) (@INC contains: . /etc/perl /usr/local/lib/arm-linux-gnueabihf/perl/5.28.1 /usr/local/share/perl/5.28.1 /usr/lib/arm-linux-gnueabihf/perl5/5.28 /usr/share/perl5 /usr/lib/arm-linux-gnueabihf/perl/5.28 /usr/share/perl/5.28 /usr/local/lib/site_perl /usr/lib/arm-linux-gnueabihf/perl-base ./FHEM ./FHEM/lib) at /usr/local/share/perl/5.28.1/Net/MQTT/Message/UnsubAck.pm line 9, <$fh> line 1522.
BEGIN failed--compilation aborted at /usr/local/share/perl/5.28.1/Net/MQTT/Message/UnsubAck.pm line 9, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::PingReq : Unmatched right curly bracket at /usr/local/share/perl/5.28.1/Net/MQTT/Message/PingReq.pm line 1, at end of line
syntax error at /usr/local/share/perl/5.28.1/Net/MQTT/Message/PingReq.pm line 1, near "}"
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::Disconnect : Can't find string terminator '"' anywhere before EOF at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Disconnect.pm line 1, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::Unsubscribe : Attempt to reload Net/MQTT/Message/Unsubscribe.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::UnsubAck : Attempt to reload Net/MQTT/Message/UnsubAck.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::PingReq : Attempt to reload Net/MQTT/Message/PingReq.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:26 1: PERL WARNING: Couldn't require Net::MQTT::Message::Disconnect : Attempt to reload Net/MQTT/Message/Disconnect.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:14:30 2: FRITZBOX fb_FritzBox7560: Define.254 Modul functionality limited because of missing perl modules: LWP::UserAgent 
2020.07.26 17:14:31 1: Including ./log/fhem.save
2020.07.26 17:14:31 1: configfile: Cannot load module TRAFFIC
Please define tra_Baunach2Coburg 5c4491da-f33f-00fd-cc03-9ffb2cc50534800d first
Cannot load module TRAFFIC
Please define tra_Baunach2Forchheim 5c4491da-f33f-00fd-84c4-4c9f3f6eafb4a389 first

2020.07.26 17:14:34 1: sendEmail for Mail [FHEM Startup Mail] returned: Jul 26 17:14:34 raspberrypi sendEmail[6399]: Email was sent successfully!
2020.07.26 17:14:34 3: Opening sys_MQTT_Broker device 127.0.0.1:1883
2020.07.26 17:14:34 3: sys_MQTT_Broker device opened
2020.07.26 17:14:34 0: Featurelevel: 6
2020.07.26 17:14:34 0: Server started with 122 defined entities (fhem.pl:21524/2020-03-27 perl:5.028001 os:linux user:fhem pid:6374)
2020.07.26 17:14:34 2: ZWDongle_ProcessSendStack: no ACK, resending message 0107000301020100f9
2020.07.26 17:14:34 3: telnetForBlockingFn_1595776474: port 38339 opened
2020.07.26 17:14:34 3: fb_FritzBox_CallMonitor device opened
2020.07.26 17:14:34 1: av_VSX_S510: Can't connect to 192.168.178.33:8102: 192.168.178.33: Keine Route zum Zielrechner (113)
2020.07.26 17:14:35 3: FRITZBOX fb_FritzBox7560: API_Check_Run.771 Cannot check for box model and APIs webcm, luaQuery and TR064 because perl modul LWP::UserAgent  is missing on this system.
2020.07.26 17:14:35 1: 192.168.178.30:9090 reappeared (htpc_KODI)

Scheinbar hat FHEM auch keine Session mehr auf die FritzBox erhalten können:

Code Auswählen

2020.07.26 17:14:44 2: FRITZBOX fb_FritzBox7560: Web_OpenCon.4501 Error: Perl modul LWP::UserAgent is missing on this system. Please install before using this modul.

Also mal die FritzBox neugestartet. Anschließend ein update von FHEM gemacht.

Ergebnis:

Code Auswählen

2020.07.26 17:19:11 0: Server shutdown
2020.07.26 17:19:11 1: Shutdown executed
2020.07.26 17:19:14 1: Including fhem.cfg
2020.07.26 17:19:14 3: telnetPort: port 7072 opened
2020.07.26 17:19:14 3: WEB: port 8083 opened
2020.07.26 17:19:15 2: eventTypes: loaded 1428 events from ./log/eventTypes.txt
2020.07.26 17:19:15 3: Opening ZWDongle_0 device /dev/ttyACM0
2020.07.26 17:19:15 3: Setting ZWDongle_0 serial parameters to 115200,8,N,1
2020.07.26 17:19:16 3: ZWDongle_0 device opened
2020.07.26 17:19:19 3: Opening av_VSX_S510 device 192.168.178.33:8102
2020.07.26 17:19:20 1: reload: Error:Modul 98_TRAFFIC deactivated:
 Unrecognized character \xA0; marked by <-- HERE after 			   <-- HERE near column 7 at /usr/share/perl5/HTTP/Response.pm line 142, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.

2020.07.26 17:19:20 0: Unrecognized character \xA0; marked by <-- HERE after 			   <-- HERE near column 7 at /usr/share/perl5/HTTP/Response.pm line 142, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/UserAgent.pm line 9, <$fh> line 899.
Compilation failed in require at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
BEGIN failed--compilation aborted at /usr/share/perl5/LWP/Simple.pm line 25, <$fh> line 899.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 899.

2020.07.26 17:19:20 1: reload: Error:Modul 98_TRAFFIC deactivated:
 Attempt to reload LWP/Simple.pm aborted.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.

2020.07.26 17:19:20 0: Attempt to reload LWP/Simple.pm aborted.
Compilation failed in require at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.
BEGIN failed--compilation aborted at ./FHEM/98_TRAFFIC.pm line 60, <$fh> line 909.

2020.07.26 17:19:20 3: Opening fb_FritzBox_CallMonitor device 192.168.178.1:1012
2020.07.26 17:19:20 3: WEBphone: port 8084 opened
2020.07.26 17:19:21 3: TABLETUI: new ext defined infix:ftui/: dir:./www/tablet:
2020.07.26 17:19:21 3: Registering HTTPSRV TABLETUI for URL /ftui   and assigned link ftui/ ...
2020.07.26 17:19:21 3: Read password from file uniqueID
2020.07.26 17:19:27 0: EGPM2LAN: Cant connect to 192.168.178.252
2020.07.26 17:19:30 3: HttpUtils: http://192.168.178.252/login.html: Can't connect(1) to http://192.168.178.252:80: IO::Socket::INET: connect: No route to host
2020.07.26 17:19:30 3: CustomGetFileFromURL http://192.168.178.252/login.html: Can't connect(1) to http://192.168.178.252:80: IO::Socket::INET: connect: No route to host
2020.07.26 17:19:31 3: [wtr_Twilight] got no weather info from yahoo. Error code: http://query.yahooapis.com/v1/public/yql?q=select%20*%20from%20weather.forecast%20where%20woeid=0%20and%20u=%27c%27&format=json&env=store%3A%2F%2Fdatatables.org%2Falltableswithkeys: Can't connect(1) to http://query.yahooapis.com:80: IO::Socket::INET: Bad hostname 'query.yahooapis.com:80'
2020.07.26 17:19:31 1: PERL WARNING: String found where operator expected at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Unsubscribe.pm line 2, at end of line
2020.07.26 17:19:31 1: PERL WARNING: 	(Missing semicolon on previous line?)
2020.07.26 17:19:31 1: PERL WARNING: Couldn't require Net::MQTT::Message::Unsubscribe : Can't find string terminator '"' anywhere before EOF at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Unsubscribe.pm line 2, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:31 1: PERL WARNING: Couldn't require Net::MQTT::Message::UnsubAck : Can't find string terminator '"' anywhere before EOF at /usr/local/share/perl/5.28.1/Net/MQTT/Message/UnsubAck.pm line 8, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:31 1: PERL WARNING: Couldn't require Net::MQTT::Message::PingReq : Unmatched right curly bracket at /usr/local/share/perl/5.28.1/Net/MQTT/Message/PingReq.pm line 1, at end of line
syntax error at /usr/local/share/perl/5.28.1/Net/MQTT/Message/PingReq.pm line 1, near "}"
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:32 1: PERL WARNING: Couldn't require Net::MQTT::Message::Disconnect : Can't find string terminator '"' anywhere before EOF at /usr/local/share/perl/5.28.1/Net/MQTT/Message/Disconnect.pm line 1, <$fh> line 1522.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:32 1: PERL WARNING: Couldn't require Net::MQTT::Message::Unsubscribe : Attempt to reload Net/MQTT/Message/Unsubscribe.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:32 1: PERL WARNING: Couldn't require Net::MQTT::Message::UnsubAck : Attempt to reload Net/MQTT/Message/UnsubAck.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:32 1: PERL WARNING: Couldn't require Net::MQTT::Message::PingReq : Attempt to reload Net/MQTT/Message/PingReq.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:32 1: PERL WARNING: Couldn't require Net::MQTT::Message::Disconnect : Attempt to reload Net/MQTT/Message/Disconnect.pm aborted.
Compilation failed in require at /usr/local/share/perl/5.28.1/Module/Pluggable/Object.pm line 24, <$fh> line 1522.
 at /usr/local/share/perl/5.28.1/Module/Pluggable.pm line 32.
2020.07.26 17:19:36 2: FRITZBOX fb_FritzBox7560: Define.254 Modul functionality limited because of missing perl modules: LWP::UserAgent 
2020.07.26 17:19:36 1: Including ./log/fhem.save
2020.07.26 17:19:39 1: sendEmail for Mail [FHEM Startup Mail] returned: Jul 26 17:19:39 raspberrypi sendEmail[6651]: Email was sent successfully!
2020.07.26 17:19:39 3: Opening sys_MQTT_Broker device 127.0.0.1:1883
2020.07.26 17:19:39 3: sys_MQTT_Broker device opened
2020.07.26 17:19:39 0: Featurelevel: 6
2020.07.26 17:19:39 0: Server started with 122 defined entities (fhem.pl:22467/2020-07-24 perl:5.028001 os:linux user:fhem pid:6627)
2020.07.26 17:19:39 2: ZWDongle_ProcessSendStack: no ACK, resending message 0107000301020100f9
2020.07.26 17:19:39 3: telnetForBlockingFn_1595776779: port 34411 opened
2020.07.26 17:19:40 1: av_VSX_S510: Can't connect to 192.168.178.33:8102: Illegal seek
2020.07.26 17:19:40 1: av_VSX_S510: Can't connect to 192.168.178.33:8102: 192.168.178.33: Keine Route zum Zielrechner (113)
2020.07.26 17:19:40 3: fb_FritzBox_CallMonitor device opened
2020.07.26 17:19:40 3: FRITZBOX fb_FritzBox7560: API_Check_Run.771 Cannot check for box model and APIs webcm, luaQuery and TR064 because perl modul LWP::UserAgent  is missing on this system.
2020.07.26 17:19:40 1: 192.168.178.30:9090 reappeared (htpc_KODI)

Danach fangen wieder die PRESENCE Fehler an und die FritzBox bekommt keine Session.

Schaut irgendwie so aus, als hätte PERL was abgekriegt, oder ist da sonst noch was im Argen? Und wenn ja: Wie bekomme ich das am besten wieder korrigiert?

Danke für eure Hilfe schon jetzt!

Otto123 · 26 Juli 2020, 17:52:12

Hi,

klingt spontan nach: Dateisystem korrupt / SD Card defekt?

Gruß Otto

r00t2 · 26 Juli 2020, 17:54:01

Nachtrag:

Ich habe versucht mit SSH das fehlende Perl Modul nach zu installieren. Ergebnis:

Code Auswählen


pi@raspberrypi:~ $ sudo cpan install LWP::UserAgent
Bareword found where operator expected at /usr/local/share/perl/5.28.1/CPAN.pm line 10, near "BEFIN     if (File::Spec->cin("rel2abs"
  (Might be a runaway multi-line "" string starting on line 1)
        (Do you need to predeclare BEFIN?)
String found where operator expected at /usr/local/share/perl/5.28.1/CPAN.pm line 10, near "# protect against ""
        (Missing semicolon on previous line?)
String found where operator expected at /usr/local/share/perl/5.28.1/CPAN.pm line 10, near "no lib ""
        (Missing semicolon on previous line?)
syntax error at /usr/local/share/perl/5.28.1/CPAN.pm line 10, near "BEFIN     if (File::Spec->cin("rel2abs"
Can't redeclare "my" in "my" at /usr/local/share/perl/5.28.1/CPAN.pm line 17, near "my"
Unmatched right curly bracket at /usr/local/share/perl/5.28.1/CPAN.pm line 41, at end of line
syntax error at /usr/local/share/perl/5.28.1/CPAN.pm line 41, near "}"
BEGIN not safe after errors--compilation aborted at /usr/local/share/perl/5.28.1/CPAN.pm line 85.
Compilation failed in require at /usr/local/share/perl/5.28.1/App/Cpan.pm line 290.
BEGIN failed--compilation aborted at /usr/local/share/perl/5.28.1/App/Cpan.pm line 290.
Compilation failed in require at /usr/local/bin/cpan line 7.
BEGIN failed--compilation aborted at /usr/local/bin/cpan line 7.
pi@raspberrypi:~ $

Klingt tatsächlich nach einem Perl Problem, oder? Wie bekomme ich das wieder hin? Oder doch wieder mal eine geschrottete SD Karte?

Zitat von: Otto123 am 26 Juli 2020, 17:52:12
Hi,

klingt spontan nach: Dateisystem korrupt / SD Card defekt?

Gruß Otto

Ich hatte gehofft, nachdem ich das ganze System an einen USV angeschlossen habe, würden sich die Probleme mit der SD Karte mal verringern... *seufz*

MadMax-FHEM · 26 Juli 2020, 18:07:05

Warum nicht einfach auf eine SSD umsteigen!?

Allerdings vorher ein "vertrauenswürdiges" Backup suchen...

Gruß, Joachim

betateilchen · 26 Juli 2020, 18:12:17

Zitat von: MadMax-FHEM am 26 Juli 2020, 18:07:05
Allerdings vorher ein "vertrauenswürdiges" Backup suchen...

Code Auswählen


#!/bin/sh

cd ~/fhembackup

backname=`date "+backup_fhemrpi3_%Y%m%d-%H%M.tar.gz"`
tar -czvf $backname /opt/fhem/sqldb/
aws s3 sync . s3://<s3BucketName/fhem
rm  $backname

#

läuft jede Nacht um 01:31 Uhr und sichert die configDB und die DbLog-DB.
Mehr brauche ich nicht, um ein FHEM wiederherstellen zu können.

r00t2 · 26 Juli 2020, 18:15:41

Das mit der SSD klingt gut - liefe das denn auch an meinem älteren RPi 2?
Ich mag den RPi 2 wirklich gerne, weil er ein echt gutes Energie/Leistungsverhältnis hat.

Ein vertrauenswürdiges Backup sollte vorhanden sein.

Otto123 · 26 Juli 2020, 18:23:05

Code Auswählen

cat /sys/firmware/devicetree/base/model
Wenn Du Revision 1.2 hast wird das mit dem Boot ohne Probleme gehen.
https://www.raspberrypi.org/documentation/hardware/raspberrypi/bootmodes/msd.md

Sonst geht es nur mit Umweg.

r00t2 · 26 Juli 2020, 19:54:56

Ich denke es ist sogar ein Rev 1.2, das klingt doch gut. Dann werde ich mich mal auf die Suche nach einer kleinen SSD machen.

Danke für eure Hilfe!

BTW: Das System zerschießt sich wohl gerade immer mehr, jetzt ist auch Sysmon schon defekt:

Code Auswählen

2020.07.26 19:52:48 1: PERL WARNING: Use of uninitialized value in int at ./FHEM/42_SYSMON.pm line 3494.
uptime: error while loading shared libraries: libc.so.6: cannot open shared object file: Error 117
sh: error while loading shared libraries: libc.so.6: cannot open shared object file: Error 117
2020.07.26 19:52:48 1: PERL WARNING: Use of uninitialized value in int at ./FHEM/42_SYSMON.pm line 3625.
2020.07.26 19:52:48 1: PERL WARNING: Argument "cat: error while loading shared libraries: libc.so.6: ca..." isn't numeric in int at ./FHEM/42_SYSMON.pm line 1765.
sh: error while loading shared libraries: libc.so.6: cannot open shared object file: Error 117
2020.07.26 19:52:48 1: PERL WARNING: Use of uninitialized value in int at ./FHEM/42_SYSMON.pm line 3516.

MadMax-FHEM · 26 Juli 2020, 20:41:09

Wenn nicht: boot-Partition weiterhin auf SD und rootfs dann auf SSD sollte auch mit "älteren" PIs gehen...

Ist halt (klein wenig) mehr Aufwand...

Gruß, Joachim

MadMax-FHEM · 26 Juli 2020, 20:44:05

Zitat von: betateilchen am 26 Juli 2020, 18:12:17
Code Auswählen Erweitern
#!/bin/sh cd ~/fhembackup backname=`date "+backup_fhemrpi3_%Y%m%d-%H%M.tar.gz"` tar -czvf $backname /opt/fhem/sqldb/ aws s3 sync . s3://<s3BucketName/fhem rm $backname #

läuft jede Nacht um 01:31 Uhr und sichert die configDB und die DbLog-DB.
Mehr brauche ich nicht, um ein FHEM wiederherstellen zu können.

Ja oder eben ein "normales" fhem Backup...

Gilt das auch, wenn die DBs auf dem PI lagen!?

Ich denke es ist egal welche Art von Backup aber nach einem SD-Problem sollte es halt ein Backup ohne evtl. Dateisystem-Fehler sein...

Mehr wollte ich nicht "sagen"...

Gruß, Joachim

r00t2 · 27 Juli 2020, 13:06:49

Danke für eure Antworten.

Ich habe jetzt mal eine 120GB SSD inklusive USB Gehäuse bestellt und teste das, wenn die Sachen da sind.

Da ich den OTP des RPi nicht unbdingt verändern möchte, werde ich die Lösung mit /boot auf einer kleinen SD-Karte und / auf der SSD nutzen. Somit sollten die Schreibzyklen auf der SD-Karte ja auch auf ein absolutes Minimum reduziert werden können.

Und die große SSD hat den Vorteil, dass ich ggf. weitere Daten darauf legen kann. Hatte da z. B. an ein paar Bilder von einer Türkamera oder aus dem Vogelhäuschen oder ähnliches gedacht. Dazu möchte ich nicht unbedingt die Backup-SSD aus dem NAS verwenden.

Werde die SD-Karte auch mal mit H2testw untersuchen lassen. Vielleicht ergibt sich da ein Bild der Fehlerquelle. Das Tool habe ich tatsächlich beim Einrichten der SD Karte damals nicht vorher drüber laufen lassen.

Die Umstellung auf eine configDB Lösung habe ich schon länger im Kopf - bin mir nur nicht sicher, wie groß tatsächlch die Vorteile der Lösung sind und wieviel Performance das kostet. Momentan lief der RPi2 ohne einen DB-Server mit einem Load Average von < 0.10 (15 min) im Schnitt... Meist so um die 0.03 bis 0.05.

MadMax-FHEM · 27 Juli 2020, 13:14:43

Zitat
Da ich den OTP des RPi nicht unbdingt verändern möchte

Sooo schlimm ist das nicht

Ist ja nur 1x mit dem Eintrag "program_usb_boot_mode=1" in der /boot/config.txt booten und gut...
...danach prüfen und den Eintrag wieder raus (nicht dass "aus Versehen" weitere PIs umgestellt werden

)...

Aber klar von SD booten und dann "den Rest" von SSD geht auch...
...aktuell (muss ich noch umstellen) bootet mein PI4 genau so...
(weil wie ich ihn bekam ging nativer USB-Boot nicht, dann nur per "Hack" und jetzt [wohl] ganz einfach so)

Halt die Einträge in /boot/cmdline.txt und /etc/fstab (auf der SSD!

) nicht vergessen anzupassen!

Gruß, Joachim

Otto123 · 27 Juli 2020, 13:31:24

Mein pi3+ hatte den OTP schon von haus aus umprogrammiert.

Ich kann da keinen Nachteil feststellen. Boot von SD geht genau wie vorher.
Ich weiß es klingt endgültig - aber ist es ein Problem?

MadMax-FHEM · 27 Juli 2020, 13:51:24

Kleine Anmerkung zum reinen USB-Boot (solltest du dich doch umentscheiden

):

ob es (noch) notwendig ist, keine Ahnung...
...allerdings zeigte mein PI3 nach der Umstellung auf NUR USB-Boot/SSD, dass ein Prozess "unnötig viel Last" erzeugt...

EDIT: "unnötig" -> ich dachte mir UI, toll, von schneller SSD usw. und dann schaue ich in top nach und immer wieder kommt da so ein "worker-Prozess" mit doch einiger CPU-Auslastung... Boot von SD und der Prozess war weg... Daher habe ich mich auf die Suche gemacht, das möglichst abzustellen

Bei Nachforschungen bekam ich dann raus, dass es wohl ein Prozess ist, der weiterhin versucht SD-Karten zu "finden/mounten"...

Wenn man KEINE SD mehr nutzen will, dann kann das "abgestellt" werden, indem man folgendes in die /boot/config.txt einträgt:

Code Auswählen


# because of SSD-boot
dtoverlay=sdtweak,poll_once

Wie geschrieben: mag bei mir nur so gewesen sein... Mag aktuell nicht mehr nötig sein... Allerdings laufen alle meine PIs (die von SSD/USB laufen) mit diesem Eintrag ohne Probleme...

Gruß, Joachim

r00t2 · 27 Juli 2020, 15:19:45

Zitat von: Otto123 am 27 Juli 2020, 13:31:24
...Ich kann da keinen Nachteil feststellen. Boot von SD geht genau wie vorher.
Ich weiß es klingt endgültig - aber ist es ein Problem?

Ein echtes "Problem" ist es nicht, aber ich fühle mich momentan besser damit, die SD noch zum Boot drin zu lassen. Was nicht heißt, dass es immer so bleiben muss, da die Hardware eh für etwas anderes als FHEM nicht mehr genutzt werden wird. Vielleicht stelle ich ja als nächstes dann auf configDB und gleichzeitig auf nur USB Boot um.

Zitat von: MadMax-FHEM am 27 Juli 2020, 13:51:24
Kleine Anmerkung zum reinen USB-Boot (solltest du dich doch umentscheiden ):
...
Gruß, Joachim

Vielen Dank für den Hinweis!