PERL WARNING bei ganz neu aufgesetzten System

[tomspatz]

Code Auswählen Erweitern

2017.08.14 18:40:48 0: Server started with 10 defined entities (fhem.pl:14854/2017-08-06 perl:5.020002 os:linux user:fhem pid:618)
2017.08.14 18:43:21 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/92_FileLog.pm line 438.
2017.08.14 18:43:21 1: stacktrace:
2017.08.14 18:43:21 1:     main::__ANON__                      called by ./FHEM/92_FileLog.pm (438)
2017.08.14 18:43:21 1:     main::FileLog_fhemwebFn             called by ./FHEM/01_FHEMWEB.pm (1387)
2017.08.14 18:43:21 1:     main::FW_doDetail                   called by ./FHEM/01_FHEMWEB.pm (1062)
2017.08.14 18:43:21 1:     main::FW_answerCall                 called by ./FHEM/01_FHEMWEB.pm (548)
2017.08.14 18:43:21 1:     main::FW_Read                       called by fhem.pl (3433)
2017.08.14 18:43:21 1:     main::CallFn                        called by fhem.pl (689)
2017.08.14 18:44:21 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/92_FileLog.pm line 438.
2017.08.14 18:44:21 1: stacktrace:
2017.08.14 18:44:21 1:     main::__ANON__                      called by ./FHEM/92_FileLog.pm (438)
2017.08.14 18:44:21 1:     main::FileLog_fhemwebFn             called by ./FHEM/01_FHEMWEB.pm (1387)
2017.08.14 18:44:21 1:     main::FW_doDetail                   called by ./FHEM/01_FHEMWEB.pm (1062)
2017.08.14 18:44:21 1:     main::FW_answerCall                 called by ./FHEM/01_FHEMWEB.pm (548)
2017.08.14 18:44:21 1:     main::FW_Read                       called by fhem.pl (3433)
2017.08.14 18:44:21 1:     main::CallFn                        called by fhem.pl (689)

Die Änderungen in diesem System sind nur global:

Code Auswählen Erweitern

Internals:
   DEF        no definition
   NAME       global
   NR         1
   STATE      no definition
   TYPE       Global
   currentlogfile ./log/fhem-2017-08-14.log
   logfile    ./log/fhem-%Y-%m-%d.log
Attributes:
   altitude   77
   autoload_undefined_devices 1
   backup_before_update 1
   configfile fhem.cfg
   dnsServer  192.168.1.15
   language   DE
   latitude   x.xxxxxx
   logfile    ./log/fhem-%Y-%m-%d.log
   longitude  x.xxxxxxx
   modpath    .
   motd       none
   restoreDirs 10
   stacktrace 1
   statefile  ./log/fhem.save
   updateInBackground 1
   userattr   cmdIcon devStateIcon devStateStyle icon sortby webCmd webCmdLabel:textField-long widgetOverride
   verbose    3
   version    fhem.pl:14854/2017-08-06

Sowie Logfile:

Code Auswählen Erweitern

Internals:
   DEF        ./log/fhem-%Y-%m-%d.log fakelog
   NAME       Logfile
   NR         12
   NTFY_ORDER 50-Logfile
   REGEXP     fakelog
   STATE      active
   TYPE       FileLog
   currentlogfile ./log/fhem-2017-08-14.log
   logfile    ./log/fhem-%Y-%m-%d.log
   READINGS:
     2017-08-14 18:40:31   linesInTheFile  0
Attributes:
   nrarchive  5

Doch me alles richtig.

Warum dann diese WARNINGS?

LG
Tom

[betateilchen]

Die Änderungen in diesem System sind nur global:

Abgesehen davon, dass Du Deine Frage in der falschen Forumrubrik gestellt hast, vermute (!) ich, dass FHEM versucht, auf die eventTypes zuzugreifen, in denen direkt nach einer Neuinstallation einfach noch nix drinsteht. Kein Grund zur Sorge.

[tomspatz]

hmmmm

wo würde das den deiner Meinung nach besser hin passen?

vermute (!) ich, dass FHEM versucht, auf die eventTypes zuzugreifen, in denen direkt nach einer Neuinstallation einfach noch nix drinsteht. Kein Grund zur Sorge.

werde es beobachten

LG
Tom

[betateilchen]

wo würde das den deiner Meinung nach besser hin passen?

Z.B in die Rubrik, die für 92_FileLog.pm zuständig ist.

https://forum.fhem.de/index.php/topic,13092.0.html

[rudolfkoenig]

Danke fuer die Meldung, habs gefixt.

Achtung bei verschobenen Themen: ich kriege keine Email, und damit ist es Zufall, ob ich das Thema entdecke.

[betateilchen]

Hallo Rudi,

kann es sein, dass Deine Änderung unerwünschte Nebenwirkungen beim allerersten Start einer neu aufgesetzten FHEM Installation hervorruft?
Es sieht so aus, als würde beim ersten Start einiges mehrfach ausgeführt, was natürlich scheitern muss. Nach einem Reboot des Systems und einem erneuten FHEM-Start funktioniert dann alles richtig:

Hier das Log vom ersten Start eines aus .deb installiertem und FHEM.

Code Auswählen Erweitern


2017.08.21 22:32:44 1: Including fhem.cfg
2017.08.21 22:32:44 3: telnetPort: port 7072 opened
2017.08.21 22:32:44 1: Including fhem.cfg
2017.08.21 22:32:44 3: telnetPort: port 7072 opened
2017.08.21 22:32:44 3: WEB: port 8083 opened
2017.08.21 22:32:44 3: WEBphone: port 8084 opened
2017.08.21 22:32:44 3: WEBtablet: port 8085 opened
2017.08.21 22:32:45 2: eventTypes: loaded 0 events from ./log/eventTypes.txt
2017.08.21 22:32:45 1: Including ./log/fhem.save
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FHEMWEB_Initialize redefined at ./FHEM/01_FHEMWEB.pm line 125.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_SecurityCheck redefined at ./FHEM/01_FHEMWEB.pm line 231.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Define redefined at ./FHEM/01_FHEMWEB.pm line 262.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Undef redefined at ./FHEM/01_FHEMWEB.pm line 297.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Read redefined at ./FHEM/01_FHEMWEB.pm line 310.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_initInform redefined at ./FHEM/01_FHEMWEB.pm line 589.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_addToWritebuffer redefined at ./FHEM/01_FHEMWEB.pm line 644.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_AsyncOutput redefined at ./FHEM/01_FHEMWEB.pm line 664.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_closeConn redefined at ./FHEM/01_FHEMWEB.pm line 702.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_serveSpecial redefined at ./FHEM/01_FHEMWEB.pm line 719.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_answerCall redefined at ./FHEM/01_FHEMWEB.pm line 731.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_addContent redefined at ./FHEM/01_FHEMWEB.pm line 1085.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_addLinks redefined at ./FHEM/01_FHEMWEB.pm line 1092.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_digestCgi redefined at ./FHEM/01_FHEMWEB.pm line 1112.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_updateHashes redefined at ./FHEM/01_FHEMWEB.pm line 1170.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_makeTable redefined at ./FHEM/01_FHEMWEB.pm line 1208.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_detailSelect redefined at ./FHEM/01_FHEMWEB.pm line 1296.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_doDetail redefined at ./FHEM/01_FHEMWEB.pm line 1326.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_makeTableFromArray redefined at ./FHEM/01_FHEMWEB.pm line 1434.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_roomIdx redefined at ./FHEM/01_FHEMWEB.pm line 1457.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_roomOverview redefined at ./FHEM/01_FHEMWEB.pm line 1475.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_alias redefined at ./FHEM/01_FHEMWEB.pm line 1655.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_makeDeviceLine redefined at ./FHEM/01_FHEMWEB.pm line 1666.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_sortIndex redefined at ./FHEM/01_FHEMWEB.pm line 1753.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_showRoom redefined at ./FHEM/01_FHEMWEB.pm line 1782.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_parseColumns redefined at ./FHEM/01_FHEMWEB.pm line 1885.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_fileList redefined at ./FHEM/01_FHEMWEB.pm line 1921.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_outputChunk redefined at ./FHEM/01_FHEMWEB.pm line 1948.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_returnFileAsStream redefined at ./FHEM/01_FHEMWEB.pm line 1958.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_fatal redefined at ./FHEM/01_FHEMWEB.pm line 2036.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_hidden redefined at ./FHEM/01_FHEMWEB.pm line 2044.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_select redefined at ./FHEM/01_FHEMWEB.pm line 2053.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_textfieldv redefined at ./FHEM/01_FHEMWEB.pm line 2073.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_textfield redefined at ./FHEM/01_FHEMWEB.pm line 2084.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_submit redefined at ./FHEM/01_FHEMWEB.pm line 2091.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_displayFileList redefined at ./FHEM/01_FHEMWEB.pm line 2102.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_fileNameToPath redefined at ./FHEM/01_FHEMWEB.pm line 2125.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_style redefined at ./FHEM/01_FHEMWEB.pm line 2149.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_iconTable redefined at ./FHEM/01_FHEMWEB.pm line 2324.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_pO redefined at ./FHEM/01_FHEMWEB.pm line 2357.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_pH redefined at ./FHEM/01_FHEMWEB.pm line 2368.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_pHPlain redefined at ./FHEM/01_FHEMWEB.pm line 2397.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_makeImage redefined at ./FHEM/01_FHEMWEB.pm line 2417.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_IconURL redefined at ./FHEM/01_FHEMWEB.pm line 2470.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_pF redefined at ./FHEM/01_FHEMWEB.pm line 2479.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_fC redefined at ./FHEM/01_FHEMWEB.pm line 2488.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Attr redefined at ./FHEM/01_FHEMWEB.pm line 2501.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_readIconsFrom redefined at ./FHEM/01_FHEMWEB.pm line 2594.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_readIcons redefined at ./FHEM/01_FHEMWEB.pm line 2633.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_iconName redefined at ./FHEM/01_FHEMWEB.pm line 2643.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_iconPath redefined at ./FHEM/01_FHEMWEB.pm line 2660.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_dev2image redefined at ./FHEM/01_FHEMWEB.pm line 2672.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_makeEdit redefined at ./FHEM/01_FHEMWEB.pm line 2729.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_longpollInfo redefined at ./FHEM/01_FHEMWEB.pm line 2762.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_roomStatesForInform redefined at ./FHEM/01_FHEMWEB.pm line 2777.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_logInform redefined at ./FHEM/01_FHEMWEB.pm line 2803.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Notify redefined at ./FHEM/01_FHEMWEB.pm line 2820.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_directNotify redefined at ./FHEM/01_FHEMWEB.pm line 2946.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_devState redefined at ./FHEM/01_FHEMWEB.pm line 2973.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Get redefined at ./FHEM/01_FHEMWEB.pm line 3085.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_Set redefined at ./FHEM/01_FHEMWEB.pm line 3113.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_closeInactiveClients redefined at ./FHEM/01_FHEMWEB.pm line 3144.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_htmlEscape redefined at ./FHEM/01_FHEMWEB.pm line 3160.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_widgetFallbackFn redefined at ./FHEM/01_FHEMWEB.pm line 3173.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_visibleDevices redefined at ./FHEM/01_FHEMWEB.pm line 3203.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_ActivateInform redefined at ./FHEM/01_FHEMWEB.pm line 3224.
2017.08.21 22:32:45 1: PERL WARNING: Subroutine FW_widgetOverride redefined at ./FHEM/01_FHEMWEB.pm line 3231.
2017.08.21 22:32:45 1: define WEB FHEMWEB 8083 global: WEB: Can't open server port at 8083: Address already in use
2017.08.21 22:32:45 1: Including ./log/fhem.save
2017.08.21 22:32:45 1: configfile: WEB: Can't open server port at 8083: Address already in use
WEBphone already defined, delete it first
WEBtablet already defined, delete it first
Logfile already defined, delete it first
autocreate already defined, delete it first
eventTypes already defined, delete it first
initialUsbCheck already defined, delete it first

2017.08.21 22:32:45 1: usb create starting
2017.08.21 22:32:46 3: Probing CUL device /dev/ttyS0
2017.08.21 22:32:47 3: Can't open /dev/ttyS0: Permission denied
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS1
2017.08.21 22:32:47 1: PERL WARNING: can't getattr: Input/output error at ./FHEM/DevIo.pm line 394.
2017.08.21 22:32:47 3: Can't open /dev/ttyS1: Input/output error
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS2
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS3
2017.08.21 22:32:47 3: Can't open /dev/ttyS3: Input/output error
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS4
2017.08.21 22:32:47 3: Can't open /dev/ttyS4: Input/output error
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS5
2017.08.21 22:32:47 3: Can't open /dev/ttyS5: Input/output error
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS6
2017.08.21 22:32:47 3: Can't open /dev/ttyS6: Input/output error
2017.08.21 22:32:47 3: Probing CUL device /dev/ttyS7
2017.08.21 22:32:47 3: Can't open /dev/ttyS7: Input/output error
2017.08.21 22:32:47 1: usb create end
2017.08.21 22:32:47 2: Messages collected while initializing FHEM: configfile: WEB: Can't open server port at 8083: Address already in use WEBphone already defined, delete it first WEBtablet already defined, delete it first Logfile already defined, delete it first autocreate already defined, delete it first eventTypes already defined, delete it first initialUsbCheck already defined, delete it first
2017.08.21 22:32:47 0: Featurelevel: 5.8
2017.08.21 22:32:47 0: Server started with 8 defined entities (fhem.pl:14933/2017-08-20 perl:5.020002 os:linux user:udo pid:26206)
2017.08.21 22:32:47 1: Error: >WEB< has no TYPE, but following keys: >CSRFTOKEN<



[rudolfkoenig]

kann es sein, dass Deine Änderung unerwünschte Nebenwirkungen beim allerersten Start einer neu aufgesetzten FHEM Installation hervorruft?

Es kann vieles sein, ich kann mir z.Zt. nur nicht vorstellen wie. Und auch nicht, wie ein OS-Reboot diese Sache aendern soll.
Kannst du bitte ein "attr global verbose 5" log (bzw perl fhem.pl -d fhem.cfg) fuer den Problemfall hier anhaengen?

[betateilchen]

Hallo Rudi, ich habe zur Zeit auch keine Idee, wo der Fehler herkommt.

Das Problem ist noch etwas komplexer:

• es tritt erst seit einigen Tagen und nur auf, wenn FHEM aus einem aktuellen .deb Paket installiert wird. Dort wird im Rahmen der Paketinstallation FHEM automatisch gestartet, deshalb ist es nicht ohne weiteres möglich, das FHEM mit einem individuellen Loglevel oder manuell mit der debug-Option zu starten
• installiert man ein FHEM komplett neu beispielsweise aus SVN, tritt das Verhalten nicht auf
• irgendwie sieht es so aus, als ob FHEM sofort zweimal gestartet wird

Das Problem ist auch hier beschrieben: https://forum.fhem.de/index.php/topic,75573.0.html

Bei Gelegenheit werde ich ein spezielles .deb bauen, in dem ein anderer Loglevel voreingestellt ist. In den nächsten Tagen wird das aber zeitlich bei mir nix.

[rudolfkoenig]

irgendwie sieht es so aus, als ob FHEM sofort zweimal gestartet wird

Stimmt, die Meldung "WEB: Can't open server port at 8083: Address already in use" deutet auf sowas. Das erklaert aber nicht die "Subroutine...redefined" Meldungen, sowas kann ich nur mit einem zweiten FHEMWEB.pm, mit 99-er Prefix erklaeren.
Wie kann ich die .deb Datei von debian.fhem.de herunterladen ohne sie zu installieren?

[betateilchen]

Stimmt, die Meldung "WEB: Can't open server port at 8083: Address already in use" deutet auf sowas.

Diese doppelte Meldung auch:

Code Auswählen Erweitern


2017.08.21 22:32:45 1: Including ./log/fhem.save
.....
2017.08.21 22:32:45 1: Including ./log/fhem.save


Downloadlink kommt per email.

[rudolfkoenig]

Downloadlink kommt per email.

Habs ausgepackt, angeschaut, und leider nichts gefunden, was das Problem erklaeren wuerde.

[betateilchen]

Habs ausgepackt, angeschaut, und leider nichts gefunden, was das Problem erklaeren wuerde.

Dann sind wir ja immerhin schon zu zweit

Ich werde mal ein paar Tests machen und mich wieder melden.

[JoWiemann]

Hallo, gibt es das Verhalten auch bei Wheezy oder nur bei Jessie und könnte es dann etwas mit der geänderten Vorgehensweise bzgl. init.d zu tun haben?


Gesendet von iPhone mit Tapatalk

Grüße Jörg

[betateilchen]

@Rudi: An der Änderung, um die es hier im Thread ursprünglich ging, liegt es jedenfalls nicht. Das habe ich gerade getestet.

[rudolfkoenig]

Ich meine (fast) alles im Log erklaeren zu koennen, wenn FHEM waehrend Startup ein HUP bekommt. Ich habe fhem.pl angepasst, damit duerfte ein HUP nicht stoeren. Wenn es nicht daran liegt, dann ist die Aenderung trotzdem sinnvoll.

Wenn diese Hypothese richtig ist, dann wurde das Problem durch Aenderungen im Paketmanager ausgeloest.