Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Homebridge sometimes does not start with homebridge-fhem plugin active #91

Open
fluxbeard opened this issue Apr 26, 2022 · 8 comments
Open

Comments

@fluxbeard
Copy link

Hello,
I am using homebridge-fhem 0.5.33 with Homebridge 1.4.0.
For a few days now my Homebridge Setup suddenly does not start after server restart with following error Message:

[homebridge-fhem] This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.

I have to restart Homebridge sometimes 2 times until the homebridge starts correctly.

@justme-1968
Copy link
Owner

are there any log entries? is fhem already running at the point of homebridge start?

@fluxbeard
Copy link
Author

No log entries in Homebridge.log apart from the above entry. Fhem is running on a different Raspberry and until a few days ago there were no problems. I restarted both raspis (Homebridge and fhem) every night at 3am. and both found each other without problems.
Now I have to restart the Homebridge Software and sometimes even the raspberry several times. The first attempts lead to the message which says that the plugin is preventing Homebridge from starting. that message is repeated and the Homebridge keeps saying: "Starting..". After several restarts the Homebridge starts fast without problems.
Now I have stopped both raspis from restarting but when I add new devices or updating plugins I have tom restart the Homebridge and then everything stops again with the error message.

@fluxbeard
Copy link
Author

Is there a specific log from Homebridge-fhem which I can search for possible errors?

@fluxbeard
Copy link
Author

�[37m[27/04/2022, 15:20:11]�[0m �[36m[HB Supervisor]�[0m Homebridge Process Ended. Code: 143, Signal: null
�[37m[27/04/2022, 15:20:16]�[0m �[36m[HB Supervisor]�[0m Restarting Homebridge...
�[37m[27/04/2022, 15:20:16]�[0m �[36m[HB Supervisor]�[0m Starting Homebridge with extra flags: -I
�[37m[27/04/2022, 15:20:16]�[0m �[36m[HB Supervisor]�[0m Started Homebridge v1.4.0 with PID: 12238
�[37m[27/04/2022, 15:20:20] �[39mLoaded config.json with 0 accessories and 7 platforms.
�[37m[27/04/2022, 15:20:21] �[39mLoaded 27 cached accessories from cachedAccessories.
�[37m[27/04/2022, 15:20:22] �[39m---
�[37m[27/04/2022, 15:20:34] �[39mLoaded plugin: homebridge-broadlink-rm-pro@4.4.10
�[37m[27/04/2022, 15:20:34] �[39mRegistering platform 'homebridge-broadlink-rm-pro.BroadlinkRM'
�[37m[27/04/2022, 15:20:34] �[39m---
�[37m[27/04/2022, 15:20:35] �[39mLoaded plugin: homebridge-camera-ui@5.0.27
�[37m[27/04/2022, 15:20:35] �[39mRegistering platform 'homebridge-camera-ui.CameraUI'
�[37m[27/04/2022, 15:20:35] �[39m---
�[37m[27/04/2022, 15:20:35] �[39mLoaded plugin: homebridge-config-ui-x@4.43.1
�[37m[27/04/2022, 15:20:35] �[39mRegistering platform 'homebridge-config-ui-x.config'
�[37m[27/04/2022, 15:20:35] �[39m---
�[37m[27/04/2022, 15:20:36] �[39mLoaded plugin: homebridge-fhem@0.5.33
homebridge API version: 2.7
this is homebridge-fhem 0.5.33
�[37m[27/04/2022, 15:20:43] �[39mRegistering platform 'homebridge-fhem.FHEM'
�[37m[27/04/2022, 15:20:43] �[39m---
�[37m[27/04/2022, 15:20:43] �[39mLoaded plugin: homebridge-fritz-platform@6.0.19
�[37m[27/04/2022, 15:20:47] �[39mRegistering platform 'homebridge-fritz-platform.FritzPlatform'
�[37m[27/04/2022, 15:20:47] �[39m---
�[37m[27/04/2022, 15:20:54] �[39mLoaded plugin: homebridge-homematicip@0.7.2
�[37m[27/04/2022, 15:20:54] �[39mRegistering platform 'homebridge-homematicip.HomematicIP'
�[37m[27/04/2022, 15:20:54] �[39m---
�[37m[27/04/2022, 15:20:54] �[39mLoaded plugin: homebridge-magichome-dynamic-platform@1.9.7
�[37m[27/04/2022, 15:20:54] �[39mRegistering platform 'homebridge-magichome-dynamic-platform.homebridge-magichome-dynamic-platform'
�[37m[27/04/2022, 15:20:54] �[39m---
�[37m[27/04/2022, 15:20:54] �[39mLoaded plugin: homebridge-rpi@1.3.21
�[37m[27/04/2022, 15:20:54] �[39mRegistering platform 'homebridge-rpi.RPi'
�[37m[27/04/2022, 15:20:54] �[39m---
�[37m[27/04/2022, 15:20:54] �[39mLoading 7 platforms...
�[37m[27/04/2022, 15:20:54] �[39m�[36m[Config]�[39m Initializing config platform...
�[37m[27/04/2022, 15:20:54] �[39m�[36m[Config]�[39m Running in Service Mode
�[37m[27/04/2022, 15:20:54] �[39m�[36m[homebridge-magichome-dynamic-platform]�[39m Initializing homebridge-magichome-dynamic-platform platform...
�[37m[27/04/2022, 15:20:54] �[39m�[36m[homebridge-magichome-dynamic-platform]�[39m �[33mFinished initializing homebridge-magichome-dynamic-platform '1.9.7'�[39m
�[37m[27/04/2022, 15:20:54] �[39m�[36m[homebridge-magichome-dynamic-platform]�[39m If this plugin brings you joy, consider visiting GitHub and giving it a ⭐.
�[37m[27/04/2022, 15:20:54] �[39m�[36m[FritzPlatform]�[39m Initializing FritzPlatform platform...
�[37m[27/04/2022, 15:21:01] �[39m�[36m[FritzPlatform]�[39m �[33mCallmonitor: There is no or no valid IP address configured this device. This device will be skipped.�[39m
�[37m[27/04/2022, 15:21:01] �[39m�[36m[RPi]�[39m Initializing RPi platform...
�[37m[27/04/2022, 15:21:01] �[39m�[36m[RPi]�[39m homebridge-rpi v1.3.21, node v16.14.2, homebridge v1.4.0, homebridge-lib v5.3.2
�[37m[27/04/2022, 15:21:01] �[39m�[36m[CameraUI]�[39m Initializing CameraUI platform...
�[37m[27/04/2022, 15:21:01] �[39m�[36m[FHEM]�[39m Initializing FHEM platform...
starting longpoll: https://192.168.1.169:8083/fhem?XHR=1&inform=type=status;addglobal=1;filter=.*;since=null;fmt=JSON&timestamp=1651065662438
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Initializing HomematicIP platform...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m homebridge-homematicip v0.7.2
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Home Security System
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Wohnungstür
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Küchenfenster
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Fenster Kinderzimmer
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Fenster Schlafzimmer
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Fenster Badezimmer
�[37m[27/04/2022, 15:21:02] �[39m�[36m[HomematicIP]�[39m Loading accessory from cache: Fenster Wohnzimmer
�[37m[27/04/2022, 15:21:02] �[39m�[36m[CameraUI]�[39m Config changed through interface, saving...
Server listening on: http://:8282
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi guest: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Deflection: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Aw: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Lock: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wps: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Dect: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi 5ghz: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi 2ghz: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi 2ghz: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi 5ghz: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi guest: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Lock: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Led: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 PhoneBook: Configuring cached accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi 2ghz: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi 5ghz: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wifi guest: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Wps: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Dect: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Aw: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Deflection: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 Lock: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m FRITZ!Box 6490 PhoneBook: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi 2ghz: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi 5ghz: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Wifi guest: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Led: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[FritzPlatform]�[39m KabelDrepeater Lock: Setup accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[CameraUI]�[39m Yi-Home: Configuring unbridged accessory...
�[37m[27/04/2022, 15:21:02] �[39m�[36m[CameraUI]�[39m Yi-Home: Setting up accessory...
�[37m[27/04/2022, 15:21:04] �[39m�[36m[CameraUI]�[39m Accessories refreshed and config.json saved!
�[37m[27/04/2022, 15:21:04] �[39mYi-Home B680 is running on port 43297.
�[37m[27/04/2022, 15:21:04] �[39mPlease add [Yi-Home B680] manually in Home app. Setup Code: 658-96-355
�[37m[27/04/2022, 15:21:05] �[39m�[36m[RPi]�[39m hardware: Raspberry Pi 2B 1.1 (1GB)
�[37m[27/04/2022, 15:21:05] �[39m�[36m[RPi]�[39m os: Raspbian GNU/Linux 11 (bullseye)
�[37m[27/04/2022, 15:21:05] �[39m�[36m[RPi]�[39m restored 2 accessories from cache
�[37m[27/04/2022, 15:21:06] �[39m�[36m[FHEM]�[39m Checking devices and attributes...
�[37m[27/04/2022, 15:21:06] �[39m�[36m[FHEM]�[39m executing: https://192.168.1.169:8083/fhem?cmd=%7BAttrVal(%22global%22,%22userattr%22,%22%22)%7D&XHR=1
�[37m[27/04/2022, 15:21:06] �[39m�[36m[FHEM]�[39m executing: https://192.168.1.169:8083/fhem?cmd=jsonlist2%20TYPE=siri&XHR=1
�[37m[27/04/2022, 15:21:06] �[39m�[36m[FHEM]�[39m Fetching FHEM devices...
�[37m[27/04/2022, 15:21:06] �[39m�[36m[FHEM]�[39m fetching: https://192.168.1.169:8083/fhem?cmd=jsonlist2%20room%3DHomekit&XHR=1
�[37m[27/04/2022, 15:21:06] �[39m�[36m[homebridge-magichome-dynamic-platform]�[39m 1 - Registering existing accessory: [ RGB Strip Schrank ]
�[37m[27/04/2022, 15:21:08] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 1, drift 1187�[39m
�[37m[27/04/2022, 15:21:09] �[39m�[36m[RPi]�[39m Pi fhem: connected to 192.168.1.169:8888
�[37m[27/04/2022, 15:21:09] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 2, drift 464�[39m
�[37m[27/04/2022, 15:21:10] �[39m�[36m[HomematicIP]�[39m Contact state of Fenster Kinderzimmer changed to OPEN
�[37m[27/04/2022, 15:21:13] �[39m�[36m[HomematicIP]�[39m Contact state of Fenster Badezimmer changed to OPEN
�[37m[27/04/2022, 15:21:14] �[39m�[36m[HomematicIP]�[39m �[33mDevice not implemented: HmIP-HAP - Access Point via type HOME_CONTROL_ACCESS_POINT�[39m
�[37m[27/04/2022, 15:21:17] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 3, drift 7823�[39m
�[37m[27/04/2022, 15:21:17] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 4, drift 6838�[39m
�[37m[27/04/2022, 15:21:17] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 5, drift 6151�[39m
�[37m[27/04/2022, 15:21:17] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 6, drift 5155�[39m
�[37m[27/04/2022, 15:21:18] �[39m�[36m[RPi]�[39m Pi fhem: Raspberry Pi 3B 1.2 (1GB) - 00000000B0F4E5D9
�[37m[27/04/2022, 15:21:19] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 7, drift 5627�[39m
�[37m[27/04/2022, 15:21:19] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 8, drift 4637�[39m
�[37m[27/04/2022, 15:21:19] �[39m�[36m[RPi]�[39m Pi homebridge: Raspberry Pi 2B 1.1 (1GB) - 000000001C8B6CC0
�[37m[27/04/2022, 15:21:19] �[39m�[36m[RPi]�[39m Pi homebridge: localhost
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 9, drift 4720�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 10, drift 3727�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 11, drift 2809�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: latest version: homebridge-lib v5.4.0�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 12, drift 1846�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 13, drift 860�[39m
�[37m[27/04/2022, 15:21:20] �[39m�[36m[HomematicIP]�[39m HmIP websocket connected.
�[37m[27/04/2022, 15:21:21] �[39m�[36m[FHEM]�[39m �[33mno siri device found. please define it.�[39m
�[37m[27/04/2022, 15:21:36] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 15, drift 15168�[39m
�[37m[27/04/2022, 15:21:36] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 16, drift 14263�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 17, drift 13289�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 18, drift 12297�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 19, drift 11301�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 20, drift 10306�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 21, drift 9314�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 22, drift 8317�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 23, drift 7319�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 24, drift 6322�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 25, drift 5386�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 26, drift 4389�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 27, drift 3406�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 28, drift 2422�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 29, drift 1767�[39m
�[37m[27/04/2022, 15:21:37] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 30, drift 823�[39m
�[37m[27/04/2022, 15:21:38] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 31, drift 936�[39m
�[37m[27/04/2022, 15:21:45] �[39m�[36m[CameraUI]�[39m Yi-Home: Setting up camera, please be patient...
�[37m[27/04/2022, 15:21:46] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 35, drift 4348�[39m
�[37m[27/04/2022, 15:21:46] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 36, drift 3364�[39m
�[37m[27/04/2022, 15:21:46] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 37, drift 2604�[39m
�[37m[27/04/2022, 15:21:46] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 38, drift 1627�[39m
�[37m[27/04/2022, 15:21:46] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 39, drift 641�[39m
�[37m[27/04/2022, 15:21:47] �[39m�[36m[CameraUI]�[39m camera.ui v5.0.27 is listening on port 8081 (http)
�[37m[27/04/2022, 15:21:51] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 43, drift 2146�[39m
�[37m[27/04/2022, 15:21:51] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 44, drift 1192�[39m
�[37m[27/04/2022, 15:21:52] �[39m�[36m[CameraUI]�[39m VIDEOANALYSIS: New message: Data: [{"zone":"region0","percent":38.59244918823242,"sensitivity":62,"dwell":60,"forceClose":3}] - Motion: detected - Camera: Yi-Home
�[37m[27/04/2022, 15:21:52] �[39m�[36m[CameraUI]�[39m Yi-Home: Motion ON
�[37m[27/04/2022, 15:21:56] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:22:07] �[39m�[36m[RPi]�[39m Pi fhem: set Current Temperature from 49.9°C to 51.5°C
�[37m[27/04/2022, 15:22:07] �[39m�[36m[RPi]�[39m Pi fhem: set Load from 0.63 to 0.51
�[37m[27/04/2022, 15:22:07] �[39m�[36m[CameraUI]�[39m Yi-Home: Motion OFF - Motion handler timeout.
�[37m[27/04/2022, 15:22:07] �[39m�[36m[RPi]�[39m Pi homebridge: set Current Temperature from 47.1°C to 47.6°C
�[37m[27/04/2022, 15:22:07] �[39m�[36m[RPi]�[39m Pi homebridge: set Load from 1.1 to 1.9
�[37m[27/04/2022, 15:22:07] �[39m�[36m[RPi]�[39m Pi homebridge: set Last Boot from "Wed Apr 27 2022 12:44:27" to "Wed Apr 27 2022 12:44:28"
�[37m[27/04/2022, 15:22:10] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 63, drift 407�[39m
�[37m[27/04/2022, 15:22:16] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:22:36] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:22:42] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 95, drift 401�[39m
�[37m[27/04/2022, 15:22:56] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:23:07] �[39m�[36m[RPi]�[39m Pi fhem: set Load from 0.51 to 0.52
�[37m[27/04/2022, 15:23:07] �[39m�[36m[RPi]�[39m Pi homebridge: set Load from 1.9 to 1.45
�[37m[27/04/2022, 15:23:10] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 123, drift 1092�[39m
�[37m[27/04/2022, 15:23:16] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:23:36] �[39m�[33m�[36m[homebridge-fhem]�[39m�[33m This plugin is taking long time to load and preventing Homebridge from starting. See https://git.io/JtMGR for more info.�[39m
�[37m[27/04/2022, 15:23:41] �[39m�[36m[RPi]�[39m �[33mwarning: heartbeat 153, drift 1236�[39m

@fluxbeard
Copy link
Author

{
"name": "FHEM",
"neverTimeout": true,
"ssl": true,
"server": "192.168.1.169",
"port": 8083,
"platform": "FHEM",
"filter": "room=Homekit",
"auth": {
"user": "username",
"pass": "password"
}

@justme-1968
Copy link
Owner

if homebridge-fhem can not connect to fhem it used to retry this indefinitely. during this time homebridge basically waits for the connection and hangs.

but the homebridge-config-x requirements state that a plugin startup may only tage x number of seconds i had to limit the maximum waiting time at startup and give up after a while.

if you restart everything and the. homebridge side is slower than the fhem side this would result in the above behavior.

please try the following:

  • will restarting only the homebridge side work better?
  • restart both sides at different times to ensure the other is running if one is restarter
  • set neverTimeout option in the fhem section of the homebridge config. this will go back to the retry indefinitely behavior, but this means that if fhem really does not start for whatever reason that homebridge will also not start.

@volschin
Copy link
Contributor

Could be that I have something in the same direction

I have also this, happening again and again.
37m[27/04/2022, 15:21:01] �[39m�[36m[FHEM]�[39m Initializing FHEM platform...
starting longpoll: https://192.168.1.169:8083/fhem?XHR=1&inform=type=status;addglobal=1;filter=.*;since=null;fmt=JSON&timestamp=1651065662438

It is Happening if my whole Docker containers are starting at the same time. Homebridge seem to be ready before FHEM is completely initialized. Restarting the Homebridge container a little bit later again fixes the problem.

@justme-1968
Copy link
Owner

yes. staggering the restarts or using neverTimeout to restore the old behavior should work here.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants