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:
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:
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
Zitat von: tomspatz am 14 August 2017, 18:53:42
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.
hmmmm
wo würde das den deiner Meinung nach besser hin passen?
Zitat
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
Zitat von: tomspatz am 14 August 2017, 21:14:43
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
Danke fuer die Meldung, habs gefixt.
Achtung bei verschobenen Themen: ich kriege keine Email, und damit ist es Zufall, ob ich das Thema entdecke.
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.
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<
Zitatkann 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?
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.
Zitatirgendwie 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?
Zitat von: rudolfkoenig am 22 August 2017, 13:54:44
Stimmt, die Meldung "WEB: Can't open server port at 8083: Address already in use" deutet auf sowas.
Diese doppelte Meldung auch:
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.
ZitatDownloadlink kommt per email.
Habs ausgepackt, angeschaut, und leider nichts gefunden, was das Problem erklaeren wuerde.
Zitat von: rudolfkoenig am 22 August 2017, 14:41:49
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.
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
@Rudi: An der Änderung, um die es hier im Thread ursprünglich ging, liegt es jedenfalls nicht. Das habe ich gerade getestet.
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.
@Rudi: scheint ein Volltreffer zu sein.
Gerade habe ich ein .deb mit der geänderten fhem.pl gebaut und FHEM läßt sich damit wieder ohne Probleme und ohne vorher notwendigen reboot installieren.
Danke für Deine Unterstützung!
Vielen Dank an euch beide.
Ich glaube damit ist ein schon länger temporär vorhandenes Problem gelöst.
Ich hatte genau diesen Effekt schon immer mal an manchen Tagen wenn ich fhem über apt-get installiert habe.
Ich habe in meinen Installationen immer diesen Eintrag Including ./log/fhem.save gesehen, mich zwar gewundert es aber nicht beachtet. Bei einer frischen Installation sollte es ja eigentlich kein fhem.save geben.
War das eine ganz zufällige zeitkritische Konstellation, die wie in den letzten Tagen auch mal stabil auftrat?
Ich habe heute versucht ein verbose 5 in die fhem.cfg im Paket zu setzen. Ich habe das Paket entpackt, modifiziert und wieder gebaut. Mit verbose 5 in der fhem.cfg lief der Start einwandfrei.
Wenn ich das gleiche Paket wieder mit verbose 3 gepackt habe, trat der Fehler wieder auf.
Gruß Otto