Ubuntu 14.04 mit fhem-5.6.deb willkürliche Crashs

[fjewl]

Hi,

nachdem ich mein FHEM von einer Raspberry basierten Version auf eine aktuelle Version auf eine virtualisierte Ubuntu 14.04 Maschine migriert habe, stürzt FHEM nach < 24h zuverlässig ab.
Von der Funktionalität passt alles, es läuft grundsätzlich ohne Fehlermeldungen, aber eben nicht besonders lange. Unterm Raspberry lief es zuverlässig und stabil (allerdings ältere Version).

Setup ist:
FHEM 5.6 installiert aus .deb Paket
Ubuntu 14.04 unter ESX
Homematic LAN und Raspbee (Huebridge) Devices

FHEM stürzt ohne weitere Log-Meldungen ab, auch im Syslog ist nichts zu finden.
Einzige Vermutung, die ich habe ist, dass ich FHEM mit apt-get -f install installieren musste aufgrund von nicht erfüllten Library Dependencies (neuere Version unter Ubuntu verfügbar).

Gibt es eine Option, dass ich FHEM gesprächiger machen kann, evtl. bekomme ich hier dann bessere Hinweise.

Viele Grüße,
fjewl

[isy]

Hallo, versuch mal

Code Auswählen Erweitern

attr global verbose 5

Der Startwert ist auf 3. Näheres in der Doku.

Gruß Helmut

[fjewl]

Hallo Helmut,

danke für den Tip mit der verbosity.
Leider habe ich damit noch nicht den Volltreffer gelandet.
Aber evtl. ein Hinweis:

1. Neustart mit verbose 5: FHEM lief < 2 Stunden

Code Auswählen Erweitern


2015.11.08 16:52:31 1: Including fhem.cfg
2015.11.08 16:52:31 3: telnetPort: port 7002 opened
2015.11.08 16:52:31 3: WEB: port 8083 opened
2015.11.08 16:52:31 3: WEBphone: port 8084 opened
2015.11.08 16:52:31 3: WEBtablet: port 8085 opened
2015.11.08 16:52:31 2: eventTypes: loaded 296 events from /opt/fhem/log/eventTypes.txt
2015.11.08 16:52:31 1: HMLAN_Parse: HMLAN1 new condition disconnected
2015.11.08 16:52:31 3: Opening HMLAN1 device 192.168.178.7:1000
2015.11.08 16:52:31 3: HMLAN1 device opened
2015.11.08 16:52:31 1: HMLAN_Parse: HMLAN1 new condition init
2015.11.08 16:52:31 3: treppenhausdg: I/O device is raspbee
2015.11.08 16:52:31 3: treppenhausog: I/O device is raspbee
2015.11.08 16:52:31 3: treppenhauskeller: I/O device is raspbee
2015.11.08 16:52:31 3: osramhkr: I/O device is raspbee
2015.11.08 16:52:31 3: wohnzimmerlinks: I/O device is raspbee
2015.11.08 16:52:31 3: wohnzimmerrechts: I/O device is raspbee
2015.11.08 16:52:31 3: wohnzimmerwohnwand: I/O device is raspbee
2015.11.08 16:52:31 3: gartenlampe: I/O device is raspbee
2015.11.08 16:52:31 3: CUL_HM_HM_CC_RT_DN_2DA22D: unknown IODev specified
2015.11.08 16:52:31 1: Including /opt/fhem/log/fhem.save
2015.11.08 16:52:31 1: configfile: CUL_HM_HM_CC_RT_DN_2DA22D: unknown IODev specified
2015.11.08 16:52:31 2: Error messages while initializing FHEM: configfile: CUL_HM_HM_CC_RT_DN_2DA22D: unknown IODev specified

(...)
letzte meldungen vor Crash sind Meldungen des HUEBridge Moduls gegen das Raspbee Device

Code Auswählen Erweitern


2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: Got data, length: 0
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: Zero length data, header follows...
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: http
2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/2: Got data, length: 450
2015.11.08 18:06:55 4: parse status message for treppenhausog
2015.11.08 18:06:55 5: Triggering treppenhausog (12 changes)
2015.11.08 18:06:55 5: Notify loop for treppenhausog colormode: ct
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog colormode: ct -> colormode: ct
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog bri: 254 -> bri: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog ct: 370 (2702K) -> ct: .* (2702K)
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog hue: 0 -> hue: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog sat: 0 -> sat: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog xy: 0.382037,0.378391 -> xy: .*,.*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog reachable: 1 -> reachable: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog alert: none -> alert: none
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog effect: none -> effect: none
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog onoff: 1 -> onoff: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog pct: 100 -> pct: .*
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog on -> on
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog state: on -> state: on
2015.11.08 18:06:55 5: Triggering treppenhausog (1 changes)
2015.11.08 18:06:55 5: Notify loop for treppenhausog RGB: ffc698
2015.11.08 18:06:55 4: eventTypes: HUEDevice treppenhausog RGB: ffc698 -> RGB: ffc698
2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: Got data, length: 0
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: Zero length data, header follows...
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: http
2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: Got data, length: 0
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: Zero length data, header follows...
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: http
2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: Got data, length: 0
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: Zero length data, header follows...
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: http
2015.11.08 18:06:55 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: Got data, length: 0
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: Zero length data, header follows...
2015.11.08 18:06:55 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: http
2015.11.08 18:06:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/7: Got data, length: 111


Nachdem FHEM hier beim Starten noch ein (altes) IODevice gemeldet hat, habe ich das Device noch aus der Config genommen.

2. Start FHEM lief sagenhaft > 24 Stunden

Code Auswählen Erweitern


2015.11.08 19:44:43 5: Initializing Type Library:
2015.11.08 19:44:43 1: Including fhem.cfg
2015.11.08 19:44:43 5: Cmd: >attr global userattr devStateIcon devStateStyle icon sortby webCmd widgetOverride<
2015.11.08 19:44:43 5: Cmd: >attr global autoload_undefined_devices 1<
2015.11.08 19:44:43 5: Cmd: >attr global logfile /opt/fhem/log/fhem-%Y-%m.log<
2015.11.08 19:44:43 5: Cmd: >attr global modpath .<
2015.11.08 19:44:43 5: Cmd: >attr global motd Hello.<
2015.11.08 19:44:43 5: Cmd: >attr global statefile /opt/fhem/log/fhem.save<
2015.11.08 19:44:43 5: Cmd: >attr global uniqueID ./FHEM/FhemUtils/uniqueID<
2015.11.08 19:44:43 5: Cmd: >attr global updateInBackground 1<
2015.11.08 19:44:43 5: Cmd: >attr global verbose 5<
2015.11.08 19:44:43 5: Cmd: >define telnetPort telnet 7002 global<
2015.11.08 19:44:43 5: Loading ./FHEM/98_telnet.pm


Auch hier beim Crash die Huebridge Meldungen

Code Auswählen Erweitern



2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/1: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/2: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/2: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/2: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/3: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/4: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/5: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: Got data, length: 0
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: Zero length data, header follows...
2015.11.09 02:41:56 4: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/6: http
2015.11.09 02:41:56 5: HUEBridge_HTTP_Request http://192.168.178.6:8080/api/9871bc006cd8a3e319196e728cfef8be/lights/7: Got data, length: 111
(END)


EIN Hinweis wären also die HUEbridge geschichte, wobei die Logmeldungen immer wieder kommen ohne Crash... (Auch zerolength-Antworten)

[fjewl]

So, nach  einer Woche stabilen Laufs hat sich gezeigt, dass es nicht an der Systemumgebung lag:
Der Raspbee hat eine Lampe in der Config verloren und ID 7 gab es nicht mehr. Mit der Fehlermeldung des Raspbee in diesem Fall kam das Huebridge nicht klar und ganz FHEM hat sich verabschiedet.