Homegear läuft nicht mehr - "Could not connect to socket"

seb · August 10, 2019, 1:40pm

Seit zwei Tagen läuft mein Homegear nicht mehr. Ich schätze nach einem Update per “apt-get”.

System: openhabian / Raspbian 9 Stretch
Homegear Version: Bis zum Ausfall auf Stable
Kommuniziere mit einem HM-CFG-LAN

m.E sehen die Logs soweit gut aus. Jedoch scheint der “StartUp” irgendwo zu hängen. Das System lauscht auch auf keinem der Ports. Das Error-Log bleibt leer beim Start. Habe das Problem sowohl bei der Stable als auch bei Testing und beim Nightly-Build. Die DB habe ich testweise auch mal gelöscht - jedoch selber Effekt.

sudo homegear -r

08/10/19 15:38:58.995 Debug: debugLevel set to 5
08/10/19 15:38:58.996 Debug: memoryDebugging set to 1
08/10/19 15:38:58.996 Debug: devLog set to 0
08/10/19 15:38:58.996 Debug: ipcLog set to 0
08/10/19 15:38:58.997 Debug: enableCoreDumps set to 1
08/10/19 15:38:58.997 Debug: setDevicePermissions set to 1
08/10/19 15:38:58.997 Debug: workingDirectory set to /var/lib/homegear/
08/10/19 15:38:58.997 Debug: socketPath set to /var/run/homegear/
08/10/19 15:38:58.997 Debug: dataPath set to /var/lib/homegear/
08/10/19 15:38:58.998 Debug: familyDataPath set to /var/lib/homegear/families/
08/10/19 15:38:58.998 Debug: databaseMemoryJournal set to 0
08/10/19 15:38:58.998 Debug: databaseWALJournal set to 1
08/10/19 15:38:58.998 Debug: databaseSynchronous set to 1
08/10/19 15:38:58.998 Debug: databaseMaxBackups set to 10
08/10/19 15:38:58.999 Debug: logfilePath set to /var/log/homegear/
08/10/19 15:38:58.999 Debug: enableUPnP set to 1
08/10/19 15:38:58.999 Debug: deviceDescriptionPath set to /etc/homegear/devices/
08/10/19 15:38:58.999 Debug: clientSettingsPath set to /etc/homegear/rpcclients.conf
08/10/19 15:38:58.999 Debug: serverSettingsPath set to /etc/homegear/rpcservers.conf
08/10/19 15:38:58.999 Debug: mqttSettingsPath set to /etc/homegear/mqtt.conf
08/10/19 15:38:59.000 Debug: familyConfigPath set to /etc/homegear/families/
08/10/19 15:38:59.000 Debug: phpIniPath set to /etc/homegear/php.ini
08/10/19 15:38:59.000 Debug: gpioPath set to /sys/class/gpio/
08/10/19 15:38:59.000 Debug: scriptPath set to /var/lib/homegear/scripts/
08/10/19 15:38:59.000 Debug: enableNodeBlue set to 1
08/10/19 15:38:59.001 Debug: nodeBluePath set to /var/lib/homegear/node-blue/
08/10/19 15:38:59.001 Debug: nodeBlueDataPath set to /var/lib/homegear/node-blue/data/
08/10/19 15:38:59.001 Debug: nodeBlueDebugOutput set to 1
08/10/19 15:38:59.001 Debug: nodeBlueEventLimit1 set to 100
08/10/19 15:38:59.001 Debug: nodeBlueEventLimit2 set to 300
08/10/19 15:38:59.002 Debug: nodeBlueEventLimit3 set to 400
08/10/19 15:38:59.002 Debug: adminUiPath set to /var/lib/homegear/admin-ui/public/
08/10/19 15:38:59.002 Debug: uiPath set to /var/lib/homegear/ui/
08/10/19 15:38:59.002 Debug: libraryPath set to /var/lib/homegear/modules/
08/10/19 15:38:59.002 Debug: firmwarePath set to /usr/share/homegear/firmware/
08/10/19 15:38:59.003 Debug: tempPath set to /var/lib/homegear/tmp/
08/10/19 15:38:59.003 Debug: lockFilePath set to /var/run/homegear/
08/10/19 15:38:59.003 Debug: lockFilePathPermissions set to 504
08/10/19 15:38:59.003 Debug: lockFilePathUser set to homegear
08/10/19 15:38:59.003 Debug: lockFilePathGroup set to homegear
08/10/19 15:38:59.004 Debug: oauthCertPath set to /etc/homegear/homegear.crt
08/10/19 15:38:59.004 Debug: oauthKeyPath set to /etc/homegear/homegear.key
08/10/19 15:38:59.004 Debug: oauthTokenLifetime set to 3600
08/10/19 15:38:59.004 Debug: oauthRefreshTokenLifetime set to 5184000
08/10/19 15:38:59.005 Debug: Added replaceClientServerAddress xmlrpc_bin://127.0.0.1:1999 xmlrpc_bin://$remoteip:1999
08/10/19 15:38:59.005 Debug: Added replaceClientServerAddress http://127.0.0.1:9292/bidcos http://$remoteip:9292/bidcos
08/10/19 15:38:59.005 Debug: waitForCorrectTime set to 1
08/10/19 15:38:59.005 Debug: prioritizeThreads set to 1
08/10/19 15:38:59.005 Debug: secureMemorySize set to 65536
08/10/19 15:38:59.006 Debug: workerThreadWindow set to 3000
08/10/19 15:38:59.006 Debug: scriptEngineMaxThreadsPerScript set to 4
08/10/19 15:38:59.006 Debug: scriptEngineMaxScriptsPerProcess set to 50
08/10/19 15:38:59.006 Debug: scriptEngineThreadCount set to 5
08/10/19 15:38:59.006 Debug: scriptEngineServerMaxConnections set to 10
08/10/19 15:38:59.006 Debug: scriptEngineWatchdogTimeout set to 60000
08/10/19 15:38:59.007 Debug: scriptEngineManualClientStart set to 0
08/10/19 15:38:59.007 Debug: nodeBlueProcessingThreadCountServer set to 5
08/10/19 15:38:59.007 Debug: nodeBlueProcessingThreadCountNodes set to 10
08/10/19 15:38:59.007 Debug: nodeBlueServerMaxConnections set to 50
08/10/19 15:38:59.007 Debug: maxNodeThreadsPerProcess set to 60
08/10/19 15:38:59.008 Debug: nodeBlueWatchdogTimeout set to 60000
08/10/19 15:38:59.008 Debug: nodeBlueManualClientStart set to 0
08/10/19 15:38:59.008 Debug: ipcThreadCount set to 5
08/10/19 15:38:59.008 Debug: ipsServerMaxConnections set to 20
08/10/19 15:38:59.008 Debug: cliServerMaxConnections set to 50
08/10/19 15:38:59.008 Debug: rpcServerMaxConnections set to 50
08/10/19 15:38:59.009 Debug: rpcServerThreadPriority set to 0
08/10/19 15:38:59.009 Debug: rpcServerThreadPolicy set to 0
08/10/19 15:38:59.009 Debug: rpcClientMaxThreads set to 50
08/10/19 15:38:59.009 Debug: rpcClientThreadPriority set to 0
08/10/19 15:38:59.009 Debug: rpcClientThreadPolicy set to 0
08/10/19 15:38:59.009 Debug: workerThreadPriority set to 0
08/10/19 15:38:59.009 Debug: workerThreadPolicy set to 0
08/10/19 15:38:59.009 Debug: physicalInterfaceThreadPriority set to 45
08/10/19 15:38:59.009 Debug: physicalInterfaceThreadPolicy set to 1
08/10/19 15:38:59.009 Debug: packetReceivedThreadPriority set to 0
08/10/19 15:38:59.009 Debug: packetReceivedThreadPolicy set to 0
08/10/19 15:38:59.009 Debug: eventThreadCount set to 5
08/10/19 15:38:59.009 Debug: eventThreadPriority set to 0
08/10/19 15:38:59.009 Debug: eventThreadPolicy set to 0
08/10/19 15:39:01.015 Could not connect to socket. Error: No such file or directory
08/10/19 15:39:01.015 Could not connect to socket. Error: No such file or directory
08/10/19 15:39:03.014 Could not connect to socket. Error: No such file or directory
08/10/19 15:39:03.014 Could not connect to socket. Error: No such file or directory
08/10/19 15:39:03.015 Could not connect to socket. Error: No such file or directory
08/10/19 15:39:03.015 Could not connect to socket. Error: No such file or directory

Beim Versuch den Service zu beenden erscheint im Log folgendes:

homegear.log
08/10/19 11:57:16.745 Info: Startup is not complete yet. Queueing shutdown.

homegear.log (Debug Level 5)

08/10/19 14:32:16.185 Starting Homegear...
08/10/19 14:32:16.185 Homegear version 0.8.0-2743
08/10/19 14:32:16.185 Git commit SHA of libhomegear-base: -
08/10/19 14:32:16.185 Git branch of libhomegear-base:     -
08/10/19 14:32:16.185 Git commit SHA of Homegear:         -
08/10/19 14:32:16.185 Git branch of Homegear:             -
08/10/19 14:32:16.185 Determining maximum thread count...
08/10/19 14:32:17.408 Maximum thread count is: 218
08/10/19 14:32:17.680 Info: Backing up database...
08/10/19 14:32:17.871 Initializing database...
08/10/19 14:32:17.899 Debug: Loading licensing modules
08/10/19 14:32:17.899 Info: Loading licensing module mod_licensing.so
08/10/19 14:32:17.901 Module Licensing: Debug: Loading module...
08/10/19 14:32:17.901 Initializing system variable controller...
08/10/19 14:32:17.913 Debug: Loading family modules
08/10/19 14:32:17.913 Info: Loading family module (type 1) mod_homematicbidcos.so
08/10/19 14:32:17.937 Info: Loading settings from /etc/homegear/families/homematicbidcos.conf
08/10/19 14:32:17.937 Debug: Loading section "General"
08/10/19 14:32:17.937 Debug: Family setting moduleenabled set to true
08/10/19 14:32:17.937 Debug: Family setting rfkey set to XXXXXXXXX
08/10/19 14:32:17.937 Debug: Family setting currentrfkeyindex set to 2
08/10/19 14:32:17.937 Debug: Family setting oldrfkey set to XXXXXXXXX
08/10/19 14:32:17.937 Debug: Family setting processbroadcastwithaesenabled set to false
08/10/19 14:32:17.937 Debug: Loading section "HM-CFG-LAN"
08/10/19 14:32:17.941 Debug: id set to My-HM-CFG-LAN
08/10/19 14:32:17.941 Debug: default set to 1
08/10/19 14:32:17.941 Debug: deviceType set to hmcfglan
08/10/19 14:32:17.941 Debug: host set to 192.168.169.4
08/10/19 14:32:17.941 Debug: port set to 1000
08/10/19 14:36:27.007 Info: Startup is not complete yet. Queueing shutdown.
08/10/19 14:36:50.395 Starting Homegear...
08/10/19 14:36:50.395 Homegear version 0.8.0-2743
08/10/19 14:36:50.395 Git commit SHA of libhomegear-base: -
08/10/19 14:36:50.395 Git branch of libhomegear-base:     -
08/10/19 14:36:50.395 Git commit SHA of Homegear:         -
08/10/19 14:36:50.395 Git branch of Homegear:             -
08/10/19 14:36:50.395 Determining maximum thread count...
08/10/19 14:36:51.609 Maximum thread count is: 217
08/10/19 14:36:51.692 Info: Backing up database...
08/10/19 14:36:51.864 Initializing database...
08/10/19 14:36:51.869 Debug: Loading licensing modules
08/10/19 14:36:51.869 Info: Loading licensing module mod_licensing.so
08/10/19 14:36:51.871 Module Licensing: Debug: Loading module...
08/10/19 14:36:51.871 Initializing system variable controller...
08/10/19 14:36:51.872 Debug: Loading family modules
08/10/19 14:36:51.872 Info: Loading family module (type 1) mod_homematicbidcos.so
08/10/19 14:36:51.885 Info: Loading settings from /etc/homegear/families/homematicbidcos.conf
08/10/19 14:36:51.885 Debug: Loading section "General"
08/10/19 14:36:51.885 Debug: Family setting moduleenabled set to true
08/10/19 14:36:51.885 Debug: Family setting rfkey set to XXXXXXXX
08/10/19 14:36:51.886 Debug: Family setting currentrfkeyindex set to 2
08/10/19 14:36:51.886 Debug: Family setting oldrfkey set to XXXXXX
08/10/19 14:36:51.886 Debug: Family setting processbroadcastwithaesenabled set to false
08/10/19 14:36:51.886 Debug: Loading section "HM-CFG-LAN"
08/10/19 14:36:51.886 Debug: id set to My-HM-CFG-LAN
08/10/19 14:36:51.886 Debug: default set to 1
08/10/19 14:36:51.886 Debug: deviceType set to hmcfglan
08/10/19 14:36:51.886 Debug: host set to 192.168.169.4
08/10/19 14:36:51.886 Debug: port set to 1000

sudo homegear -u homegear -g homegear -d

08/10/19 14:56:05.656 Debug: debugLevel set to 5
08/10/19 14:56:05.656 Debug: memoryDebugging set to 1
08/10/19 14:56:05.657 Debug: devLog set to 0
08/10/19 14:56:05.657 Debug: ipcLog set to 0
08/10/19 14:56:05.657 Debug: enableCoreDumps set to 1
08/10/19 14:56:05.657 Debug: setDevicePermissions set to 1
08/10/19 14:56:05.657 Debug: workingDirectory set to /var/lib/homegear/
08/10/19 14:56:05.657 Debug: socketPath set to /var/run/homegear/
08/10/19 14:56:05.657 Debug: dataPath set to /var/lib/homegear/
08/10/19 14:56:05.657 Debug: familyDataPath set to /var/lib/homegear/families/
08/10/19 14:56:05.657 Debug: databaseMemoryJournal set to 0
08/10/19 14:56:05.657 Debug: databaseWALJournal set to 1
08/10/19 14:56:05.657 Debug: databaseSynchronous set to 1
08/10/19 14:56:05.657 Debug: databaseMaxBackups set to 10
08/10/19 14:56:05.657 Debug: logfilePath set to /var/log/homegear/
08/10/19 14:56:05.657 Debug: enableUPnP set to 1
08/10/19 14:56:05.658 Debug: deviceDescriptionPath set to /etc/homegear/devices/
08/10/19 14:56:05.658 Debug: clientSettingsPath set to /etc/homegear/rpcclients.conf
08/10/19 14:56:05.658 Debug: serverSettingsPath set to /etc/homegear/rpcservers.conf
08/10/19 14:56:05.658 Debug: mqttSettingsPath set to /etc/homegear/mqtt.conf
08/10/19 14:56:05.658 Debug: familyConfigPath set to /etc/homegear/families/
08/10/19 14:56:05.658 Debug: phpIniPath set to /etc/homegear/php.ini
08/10/19 14:56:05.658 Debug: gpioPath set to /sys/class/gpio/
08/10/19 14:56:05.658 Debug: scriptPath set to /var/lib/homegear/scripts/
08/10/19 14:56:05.658 Debug: enableNodeBlue set to 1
08/10/19 14:56:05.658 Debug: nodeBluePath set to /var/lib/homegear/node-blue/
08/10/19 14:56:05.659 Debug: nodeBlueDataPath set to /var/lib/homegear/node-blue/data/
08/10/19 14:56:05.659 Debug: nodeBlueDebugOutput set to 1
08/10/19 14:56:05.659 Debug: nodeBlueEventLimit1 set to 100
08/10/19 14:56:05.659 Debug: nodeBlueEventLimit2 set to 300
08/10/19 14:56:05.659 Debug: nodeBlueEventLimit3 set to 400
08/10/19 14:56:05.659 Debug: adminUiPath set to /var/lib/homegear/admin-ui/public/
08/10/19 14:56:05.659 Debug: uiPath set to /var/lib/homegear/ui/
08/10/19 14:56:05.659 Debug: libraryPath set to /var/lib/homegear/modules/
08/10/19 14:56:05.659 Debug: firmwarePath set to /usr/share/homegear/firmware/
08/10/19 14:56:05.659 Debug: tempPath set to /var/lib/homegear/tmp/
08/10/19 14:56:05.660 Debug: lockFilePath set to /var/run/homegear/
08/10/19 14:56:05.660 Debug: lockFilePathPermissions set to 504
08/10/19 14:56:05.660 Debug: lockFilePathUser set to homegear
08/10/19 14:56:05.660 Debug: lockFilePathGroup set to homegear
08/10/19 14:56:05.660 Debug: oauthCertPath set to /etc/homegear/homegear.crt
08/10/19 14:56:05.660 Debug: oauthKeyPath set to /etc/homegear/homegear.key
08/10/19 14:56:05.660 Debug: oauthTokenLifetime set to 3600
08/10/19 14:56:05.660 Debug: oauthRefreshTokenLifetime set to 5184000
08/10/19 14:56:05.661 Debug: Added replaceClientServerAddress xmlrpc_bin://127.0.0.1:1999 xmlrpc_bin://$remoteip:1999
08/10/19 14:56:05.661 Debug: Added replaceClientServerAddress http://127.0.0.1:9292/bidcos http://$remoteip:9292/bidcos
08/10/19 14:56:05.661 Debug: waitForCorrectTime set to 1
08/10/19 14:56:05.661 Debug: prioritizeThreads set to 1
08/10/19 14:56:05.661 Debug: secureMemorySize set to 65536
08/10/19 14:56:05.661 Debug: workerThreadWindow set to 3000
08/10/19 14:56:05.661 Debug: scriptEngineMaxThreadsPerScript set to 4
08/10/19 14:56:05.661 Debug: scriptEngineMaxScriptsPerProcess set to 50
08/10/19 14:56:05.661 Debug: scriptEngineThreadCount set to 5
08/10/19 14:56:05.661 Debug: scriptEngineServerMaxConnections set to 10
08/10/19 14:56:05.661 Debug: scriptEngineWatchdogTimeout set to 60000
08/10/19 14:56:05.661 Debug: scriptEngineManualClientStart set to 0
08/10/19 14:56:05.662 Debug: nodeBlueProcessingThreadCountServer set to 5
08/10/19 14:56:05.662 Debug: nodeBlueProcessingThreadCountNodes set to 10
08/10/19 14:56:05.662 Debug: nodeBlueServerMaxConnections set to 50
08/10/19 14:56:05.662 Debug: maxNodeThreadsPerProcess set to 60
08/10/19 14:56:05.662 Debug: nodeBlueWatchdogTimeout set to 60000
08/10/19 14:56:05.662 Debug: nodeBlueManualClientStart set to 0
08/10/19 14:56:05.662 Debug: ipcThreadCount set to 5
08/10/19 14:56:05.662 Debug: ipsServerMaxConnections set to 20
08/10/19 14:56:05.662 Debug: cliServerMaxConnections set to 50
08/10/19 14:56:05.662 Debug: rpcServerMaxConnections set to 50
08/10/19 14:56:05.662 Debug: rpcServerThreadPriority set to 0
08/10/19 14:56:05.662 Debug: rpcServerThreadPolicy set to 0
08/10/19 14:56:05.663 Debug: rpcClientMaxThreads set to 50
08/10/19 14:56:05.663 Debug: rpcClientThreadPriority set to 0
08/10/19 14:56:05.663 Debug: rpcClientThreadPolicy set to 0
08/10/19 14:56:05.663 Debug: workerThreadPriority set to 0
08/10/19 14:56:05.663 Debug: workerThreadPolicy set to 0
08/10/19 14:56:05.663 Debug: physicalInterfaceThreadPriority set to 45
08/10/19 14:56:05.663 Debug: physicalInterfaceThreadPolicy set to 1
08/10/19 14:56:05.663 Debug: packetReceivedThreadPriority set to 0
08/10/19 14:56:05.663 Debug: packetReceivedThreadPolicy set to 0
08/10/19 14:56:05.663 Debug: eventThreadCount set to 5
08/10/19 14:56:05.663 Debug: eventThreadPriority set to 0
08/10/19 14:56:05.663 Debug: eventThreadPolicy set to 0
08/10/19 14:56:05.666 Loading RPC server settings from /etc/homegear/rpcservers.conf
08/10/19 14:56:05.667 Debug: familyServer of server FamilyRPCServer set to 1
08/10/19 14:56:05.667 Debug: interface of server FamilyRPCServer set to 0.0.0.0
08/10/19 14:56:05.667 Debug: port of server FamilyRPCServer set to 2000
08/10/19 14:56:05.667 Debug: xmlrpcServer of server FamilyRPCServer set to 1
08/10/19 14:56:05.667 Debug: jsonrpcServer of server FamilyRPCServer set to 1
08/10/19 14:56:05.667 Debug: ssl of server FamilyRPCServer set to 0
08/10/19 14:56:05.668 Debug: authType of server FamilyRPCServer set to 1
08/10/19 14:56:05.668 Debug: interface of server RPCServer1 set to 0.0.0.0
08/10/19 14:56:05.668 Debug: port of server RPCServer1 set to 2001
08/10/19 14:56:05.668 Debug: xmlrpcServer of server RPCServer1 set to 1
08/10/19 14:56:05.668 Debug: jsonrpcServer of server RPCServer1 set to 1
08/10/19 14:56:05.668 Debug: webServer of server RPCServer1 set to 1
08/10/19 14:56:05.668 Debug: restServer of server RPCServer1 set to 1
08/10/19 14:56:05.668 Debug: contentPath of RPC server RPCServer1 set to /var/lib/homegear/www/rpc/
08/10/19 14:56:05.668 Debug: webSocket of server RPCServer1 set to 1
08/10/19 14:56:05.669 Debug: ssl of server RPCServer1 set to 0
08/10/19 14:56:05.669 Debug: authType of server RPCServer1 set to 1
08/10/19 14:56:05.669 Debug: webSocketAuthType of server RPCServer1 set to 8
08/10/19 14:56:05.669 Debug: cacheAssets of server RPCServer1 set to 2592000
08/10/19 14:56:05.669 Debug: interface of server RPCServer2 set to 0.0.0.0
08/10/19 14:56:05.669 Debug: port of server RPCServer2 set to 2002
08/10/19 14:56:05.669 Debug: xmlrpcServer of server RPCServer2 set to 1
08/10/19 14:56:05.669 Debug: jsonrpcServer of server RPCServer2 set to 1
08/10/19 14:56:05.669 Debug: webServer of server RPCServer2 set to 1
08/10/19 14:56:05.669 Debug: restServer of server RPCServer2 set to 1
08/10/19 14:56:05.669 Debug: contentPath of RPC server RPCServer2 set to /var/lib/homegear/www/rpc/
08/10/19 14:56:05.669 Debug: webSocket of server RPCServer2 set to 1
08/10/19 14:56:05.670 Debug: ssl of server RPCServer2 set to 1
08/10/19 14:56:05.670 Debug: authType of server RPCServer2 set to 1
08/10/19 14:56:05.670 Debug: webSocketAuthType of server RPCServer2 set to 8
08/10/19 14:56:05.670 Debug: caPath of server RPCServer2 set to /etc/homegear/ca/cacert.pem
08/10/19 14:56:05.670 Debug: certPath of server RPCServer2 set to /etc/homegear/homegear.crt
08/10/19 14:56:05.670 Debug: keyPath of server RPCServer2 set to /etc/homegear/homegear.key
08/10/19 14:56:05.670 Debug: dhParamPath of server RPCServer2 set to /etc/homegear/dh1024.pem
08/10/19 14:56:05.670 Debug: cacheAssets of server RPCServer2 set to 2592000
08/10/19 14:56:05.670 Debug: interface of server RPCServer3 set to 0.0.0.0
08/10/19 14:56:05.670 Debug: port of server RPCServer3 set to 2003
08/10/19 14:56:05.670 Debug: xmlrpcServer of server RPCServer3 set to 1
08/10/19 14:56:05.670 Debug: jsonrpcServer of server RPCServer3 set to 1
08/10/19 14:56:05.670 Debug: webServer of server RPCServer3 set to 1
08/10/19 14:56:05.670 Debug: restServer of server RPCServer3 set to 1
08/10/19 14:56:05.670 Debug: contentPath of RPC server RPCServer3 set to /var/lib/homegear/www/rpc/
08/10/19 14:56:05.671 Debug: webSocket of server RPCServer3 set to 1
08/10/19 14:56:05.671 Debug: ssl of server RPCServer3 set to 1
08/10/19 14:56:05.671 Debug: authType of server RPCServer3 set to 6
08/10/19 14:56:05.671 Debug: webSocketAuthType of server RPCServer3 set to 8
08/10/19 14:56:05.671 Debug: caPath of server RPCServer3 set to /etc/homegear/ca/cacert.pem
08/10/19 14:56:05.671 Debug: certPath of server RPCServer3 set to /etc/homegear/homegear.crt
08/10/19 14:56:05.671 Debug: keyPath of server RPCServer3 set to /etc/homegear/homegear.key
08/10/19 14:56:05.671 Debug: dhParamPath of server RPCServer3 set to /etc/homegear/dh1024.pem
08/10/19 14:56:05.671 Debug: cacheAssets of server RPCServer3 set to 2592000
08/10/19 14:56:05.671 Debug: cacheAssets of server RPCServer3 set to 2592000
08/10/19 14:56:05.671 Debug: cacheAssets of server RPCServer3 set to 2592000
08/10/19 14:56:05.671 Loading RPC client settings from /etc/homegear/rpcclients.conf
08/10/19 14:56:05.672 Debug: hostname of RPC client ExampleClient1 set to myhostname
08/10/19 14:56:05.672 Debug: caFile of RPC client ExampleClient1 set to /path/to/ca-certficate
08/10/19 14:56:05.672 Debug: verifyCertificate of RPC client ExampleClient1 set to 1
08/10/19 14:56:05.672 Debug: forceSSL of RPC client ExampleClient1 set to 0
08/10/19 14:56:05.672 Debug: authType of RPC client ExampleClient1 set to 1
08/10/19 14:56:05.672 Debug: userName of RPC client ExampleClient1 set to myuser
08/10/19 14:56:05.672 Debug: password of RPC client ExampleClient1 was set.
08/10/19 14:56:05.672 Debug: certFile of RPC client ExampleClient1 set to /path/to/client.crt
08/10/19 14:56:05.672 Debug: keyFile of RPC client ExampleClient1 set to /path/to/client.key
08/10/19 14:56:05.673 Debug: retries of RPC client ExampleClient1 set to 3
08/10/19 14:56:05.673 Debug: timeout of RPC client ExampleClient1 set to 15000000
08/10/19 14:56:05.673 Debug: hostname of RPC client ExampleClient2 set to 192.168.178.89
08/10/19 14:56:05.673 Debug: retries of RPC client ExampleClient2 set to 5
08/10/19 14:56:05.673 Debug: timeout of RPC client ExampleClient2 set to 30000000
08/10/19 14:56:05.673 Debug (MQTT settings): enabled set to 0
08/10/19 14:56:05.673 Debug (MQTT settings): brokerHostname set to localhost
08/10/19 14:56:05.673 Debug (MQTT settings): brokerPort set to 1883
08/10/19 14:56:05.674 Debug (MQTT settings): clientName set to Homegear
08/10/19 14:56:05.674 Debug (MQTT settings): prefix set to homegear/
08/10/19 14:56:05.674 Debug (MQTT settings): homegearId set to 1234-5678-9abc
08/10/19 14:56:05.674 Debug (MQTT settings): retain set to 1
08/10/19 14:56:05.674 Debug (MQTT settings): processingThreadCount set to 5
08/10/19 14:56:05.674 Debug (MQTT settings): plainTopic set to 1
08/10/19 14:56:05.674 Debug (MQTT settings): jsonTopic set to 1
08/10/19 14:56:05.674 Debug (MQTT settings): jsonobjTopic set to 1
08/10/19 14:56:05.674 Debug (MQTT settings): enableSSL set to
08/10/19 14:56:05.681 Info: Setting allowed core file size to "4294967295" for user with id 0 and group with id 0.
08/10/19 14:56:05.682 Info: Core file size now is "4294967295".
08/10/19 14:56:05.682 Info: Setting maximum thread priority to "0" for user with id 0 and group with id 0.
08/10/19 14:56:05.682 Info: Maximum thread priority now is "0".

homegear-v

Homegear version 0.8.0-2743
Copyright (c) 2013-2019 Homegear GmbH

Git commit SHA of libhomegear-base: -
Git branch of libhomegear-base:     -
Git commit SHA of Homegear:         -
Git branch of Homegear:             -

PHP (License: PHP License):
This product includes PHP software, freely available from <http://www.php.net/software/>
Copyright (c) 1999-2019 The PHP Group. All rights reserved.

netstat

[14:53:54] openhabian@oh:~$ sudo netstat -tulpn | grep LISTEN
tcp        0      0 0.0.0.0:9001            0.0.0.0:*               LISTEN      529/node
tcp        0      0 0.0.0.0:139             0.0.0.0:*               LISTEN      653/smbd
tcp        0      0 127.0.0.1:8086          0.0.0.0:*               LISTEN      531/influxd
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN      624/sshd
tcp        0      0 127.0.0.1:8088          0.0.0.0:*               LISTEN      531/influxd
tcp        0      0 0.0.0.0:445             0.0.0.0:*               LISTEN      653/smbd
tcp6       0      0 127.0.0.1:8101          :::*                    LISTEN      528/java
tcp6       0      0 :::139                  :::*                    LISTEN      653/smbd
tcp6       0      0 127.0.0.1:46285         :::*                    LISTEN      528/java
tcp6       0      0 :::5007                 :::*                    LISTEN      528/java
tcp6       0      0 :::8080                 :::*                    LISTEN      528/java
tcp6       0      0 :::22                   :::*                    LISTEN      624/sshd
tcp6       0      0 :::3000                 :::*                    LISTEN      530/grafana-server
tcp6       0      0 :::8443                 :::*                    LISTEN      528/java
tcp6       0      0 :::445                  :::*                    LISTEN      653/smbd

pmayer · August 10, 2019, 2:44pm

Hui… @sathya, kannst du was dazu sagen?

sathya · August 12, 2019, 11:17pm

Hallo @seb,

hmm, mit der Option -d gab es durch einen Fix einen Fehler (ist im aktuellen Nightly gefixt). Ich nehme mal an, das Log ist vollständig? Startet Homegear, wenn du in der homematicbidcos.conf moduleEnabled = false setzt?

Installier auch noch einmal das aktuelle Nightly.

Viele Grüße

Sathya

seb · August 13, 2019, 12:40am

Hallo @sathya,
Habe soeben das Update auf das aktuelle Nightly Build gemacht.

Leider startet Homegear weiterhin nicht - auch nicht wenn ich das Module auf „false“ setze.

08/13/19 02:31:17.018 Starting Homegear...
08/13/19 02:31:17.019 Homegear version 0.8.0-2750
08/13/19 02:31:17.019 Git commit SHA of libhomegear-base: -
08/13/19 02:31:17.019 Git branch of libhomegear-base:     -
08/13/19 02:31:17.019 Git commit SHA of Homegear:         -
08/13/19 02:31:17.019 Git branch of Homegear:             -
08/13/19 02:31:17.019 Determining maximum thread count...
08/13/19 02:31:18.304 Maximum thread count is: 217
08/13/19 02:31:18.348 Info: Backing up database...
08/13/19 02:31:18.556 Initializing database...
08/13/19 02:31:18.569 Debug: Loading licensing modules
08/13/19 02:31:18.569 Info: Loading licensing module mod_licensing.so
08/13/19 02:31:18.572 Module Licensing: Debug: Loading module...
08/13/19 02:31:18.572 Initializing system variable controller...
08/13/19 02:31:18.574 Debug: Loading family modules
08/13/19 02:31:18.574 Info: Loading family module (type 1) mod_homematicbidcos.so
08/13/19 02:31:18.600 Info: Loading settings from /etc/homegear/families/homematicbidcos.conf
08/13/19 02:31:18.600 Debug: Loading section "General"
08/13/19 02:31:18.600 Debug: Family setting moduleenabled set to false
08/13/19 02:31:18.601 Debug: Family setting rfkey set to XXXXXX
08/13/19 02:31:18.601 Debug: Family setting currentrfkeyindex set to 2
08/13/19 02:31:18.601 Debug: Family setting oldrfkey set to XXXXX
08/13/19 02:31:18.601 Debug: Family setting processbroadcastwithaesenabled set to false
08/13/19 02:31:18.601 Debug: Loading section "HM-CFG-LAN"
08/13/19 02:31:18.601 Debug: id set to My-HM-CFG-LAN
08/13/19 02:31:18.601 Debug: default set to 1
08/13/19 02:31:18.602 Debug: deviceType set to hmcfglan
08/13/19 02:31:18.602 Debug: host set to 192.168.169.4
08/13/19 02:31:18.602 Debug: port set to 1000

sudo homegear-r

08/13/19 02:38:22.623 Could not connect to socket. Error: No such file or directory
08/13/19 02:38:22.624 Could not connect to socket. Error: No such file or directory
08/13/19 02:38:24.623 Could not connect to socket. Error: No such file or directory

pmayer · August 13, 2019, 7:52am

Steht was in homegear.err?

Du könntest in der config das Debuglevel mal höher setzen und dann neu starten.

sathya · August 14, 2019, 5:29am

Hallo @seb,

irgendetwas stimmt da ganz und gar nicht. Homegear bleibt mitten im Ladevorgang aus nicht ersichtlichen Gründen hängen. Das einzige, was du noch machen könntest wäre ein

apt-get -f install

Falls sich noch inkompatible Module irgendwo rumtummeln. Ansonsten würde ich das System noch einmal neu aufsetzen.

Viele Grüße

Sathya

P. S.:

Wenn du tiefer schauen mächtest, wo er hängt, geht das mit:

# Buster:
apt install gdb libc6-dbg libstdc++6-8-dbg

# Stretch:
apt install gdb libc6-dbg libstdc++6-6-dbg

# Beide:
gdb homegear $(pidof homegear)
thread apply all bt

kielerjung · October 14, 2019, 1:01pm

Dasselbe hier.

Im gdb hängt es mit:

thread 1 (Thread 0xb35e7010 (LWP 1412)):
#0 std::__cxx11::basic_string<char, std::char_traits, std::allocator >::operator[] (__pos=, this=) at /usr/include/c++/8/bits/basic_string.h:1046
#1 BaseLib::Rpc::JsonDecoder::decodeNumber (json=“4817893E53B44BBD265C4C06DAAAAAAA”, pos=@0xbecbd840: 9, value=<error reading variable: Cannot access memory at address 0x4>) at Encoding/JsonDecoder.cpp:967
#2 0xb6bf7214 in BaseLib::Rpc::JsonDecoder::decodeValue (json=“4817893E53B44BBD265C4C06DAAAAAAA”, pos=@0xbecbd840: 9, value=std::shared_ptr (use count 1, weak count 0) = {…}) at Encoding/JsonDecoder.cpp:788
#3 0xb6bf8634 in BaseLib::Rpc::JsonDecoder::decode (json=“4817893E53B44BBD265C4C06DAAAAAAA”) at Encoding/JsonDecoder.cpp:50"

(lankey an den letzten Stellen auf AAAAAA verändert…)

sathya · October 25, 2019, 2:01pm

Hallo @kielerjung,

welche Homegear-Version nutzt du? Hast du ein Log dazu? Wie geht der Dump weiter? #1 bis #3 sind Bibliotheksaufrufe. Das JSON ist Müll und die Frage ist, warum versucht wird, es zu dekodieren. decodeNumber wird immer dann ausgeführt, wenn der Datentyp des JSON unbekannt ist. In diesem Fall sollte die Funktion false zurückliefern und das JSON als String interpretieren. Ändert zwar nichts am Müll, aber hängen bleiben sollte nichts.

Viele Grüße

Sathya

kielerjung · October 25, 2019, 6:57pm

Es scheint in der Tat am Parsing zu liegen:

Setze ich lanKey = IRGENDWASINDERRICHTIGENLAENGE

dann stockt Homegear mit

10/25/19 18:49:32.713 Debug: Family setting centraladdress set to 0xfd0001
10/25/19 18:49:32.713 Debug: Family setting rfkey set to schnipp
10/25/19 18:49:32.714 Debug: Family setting currentrfkeyindex set to 1
10/25/19 18:49:32.714 Debug: Family setting processbroadcastwithaesenabled set to false
10/25/19 18:49:32.714 Debug: Loading section “HM-CFG-LAN”
10/25/19 18:49:32.715 Debug: id set to My-HM-CFG-LAN
10/25/19 18:49:32.715 Debug: default set to 1
10/25/19 18:49:32.716 Debug: deviceType set to hmcfglan
10/25/19 18:49:32.716 Debug: host set to 192.168.XX.YY
10/25/19 18:49:32.717 Debug: port set to 1000

Danach hängt Homegear mit 100% CPU

Setze ich lanKey = “IRGENDWAS…”

dann erhalte ich den Fehler

“Error: The AES key specified in homematicbidcos.conf for communication with your HM-CFG-LAN has the wrong size.” (Was ja korrekt ist, vermutlich gerade wegen der Anführungszeichen…)

Aber dann läuft Homegear zunächst weiter und kommuniziert mit dem HM-CFG-LAN, was aber dann mangels korrektem Key scheitert.

Die Version ist

homegear 0.7.40-2948 armhf

sathya · October 25, 2019, 7:30pm

Hallo @kielerjung,

magst du mal ein vollständiges Log vom Start bis zum Hängen auf Loglevel 5 hier posten?

Viele Grüße

Sathya

sathya · October 25, 2019, 7:31pm

Welches System hast du? Falls einen Raspberry Pi, läuft darauf die Raspbian-Version von Homegear (die Debian-Version wäre eine mögliche Fehlerursache)?

seb · October 29, 2019, 3:21pm

@kielerjung Konntest du den Fehler bereits beheben? Bei mir ist ist exakt die gleiche Situation. Sobald ich den “korrekten” lanKey setze, hängt das Log an derselben Stelle. Gebe ich einen x-beliebigen Key ein, startet Homegear und kann halt keine Verbindung herstellen.

seb · October 31, 2019, 6:36pm

@sathya @pmayer ich hatte jetzt endlich mal Zeit mich wieder um das Thema zu kümmern. Mittlerweile habe ich diverse Installationen (Docker, RPI, VM, Ubuntu, Debian) mit Stable, Testing und auch dem Nightly getestet. In jedem Fall hängt er beim Start-Up beim laden (parsen) des LanKeys.

Ein “manuelles” starten von Homegear mit Debug-Level sieht wiefolgt aus:

10/31/19 19:28:31.440 Debug: debugLevel set to 10
10/31/19 19:28:31.441 Debug: memoryDebugging set to 1
10/31/19 19:28:31.441 Debug: devLog set to 1
10/31/19 19:28:31.441 Debug: ipcLog set to 0
10/31/19 19:28:31.441 Debug: enableCoreDumps set to 1
10/31/19 19:28:31.441 Debug: setDevicePermissions set to 1
10/31/19 19:28:31.441 Debug: workingDirectory set to /var/lib/homegear/
10/31/19 19:28:31.441 Debug: socketPath set to /var/run/homegear/
10/31/19 19:28:31.441 Debug: dataPath set to /var/lib/homegear/
10/31/19 19:28:31.441 Debug: familyDataPath set to /var/lib/homegear/families/
10/31/19 19:28:31.441 Debug: databaseMemoryJournal set to 0
10/31/19 19:28:31.441 Debug: databaseWALJournal set to 1
10/31/19 19:28:31.441 Debug: databaseSynchronous set to 1
10/31/19 19:28:31.441 Debug: databaseMaxBackups set to 10
10/31/19 19:28:31.441 Debug: logfilePath set to /var/log/homegear/
10/31/19 19:28:31.441 Debug: enableUPnP set to 1
10/31/19 19:28:31.441 Debug: deviceDescriptionPath set to /etc/homegear/devices/
10/31/19 19:28:31.442 Debug: clientSettingsPath set to /etc/homegear/rpcclients.conf
10/31/19 19:28:31.442 Debug: serverSettingsPath set to /etc/homegear/rpcservers.conf
10/31/19 19:28:31.442 Debug: mqttSettingsPath set to /etc/homegear/mqtt.conf
10/31/19 19:28:31.442 Debug: familyConfigPath set to /etc/homegear/families/
10/31/19 19:28:31.442 Debug: phpIniPath set to /etc/homegear/php.ini
10/31/19 19:28:31.442 Debug: gpioPath set to /sys/class/gpio/
10/31/19 19:28:31.442 Debug: scriptPath set to /var/lib/homegear/scripts/
10/31/19 19:28:31.442 Debug: enableNodeBlue set to 1
10/31/19 19:28:31.442 Debug: nodeBluePath set to /var/lib/homegear/node-blue/
10/31/19 19:28:31.442 Debug: nodeBlueDataPath set to /var/lib/homegear/node-blue/data/
10/31/19 19:28:31.442 Debug: nodeBlueDebugOutput set to 1
10/31/19 19:28:31.442 Debug: nodeBlueEventLimit1 set to 100
10/31/19 19:28:31.442 Debug: nodeBlueEventLimit2 set to 300
10/31/19 19:28:31.442 Debug: nodeBlueEventLimit3 set to 400
10/31/19 19:28:31.442 Debug: adminUiPath set to /var/lib/homegear/admin-ui/public/
10/31/19 19:28:31.443 Debug: uiPath set to /var/lib/homegear/ui/
10/31/19 19:28:31.443 Debug: libraryPath set to /var/lib/homegear/modules/
10/31/19 19:28:31.443 Debug: firmwarePath set to /usr/share/homegear/firmware/
10/31/19 19:28:31.443 Debug: tempPath set to /var/lib/homegear/tmp/
10/31/19 19:28:31.443 Debug: lockFilePath set to /var/run/homegear/
10/31/19 19:28:31.443 Debug: lockFilePathPermissions set to 504
10/31/19 19:28:31.443 Debug: lockFilePathUser set to homegear
10/31/19 19:28:31.443 Debug: lockFilePathGroup set to homegear
10/31/19 19:28:31.443 Debug: oauthCertPath set to /etc/homegear/homegear.crt
10/31/19 19:28:31.443 Debug: oauthKeyPath set to /etc/homegear/homegear.key
10/31/19 19:28:31.443 Debug: oauthTokenLifetime set to 3600
10/31/19 19:28:31.443 Debug: oauthRefreshTokenLifetime set to 5184000
10/31/19 19:28:31.443 Debug: Added replaceClientServerAddress xmlrpc_bin://127.0.0.1:1999 xmlrpc_bin://$remoteip:1999
10/31/19 19:28:31.443 Debug: Added replaceClientServerAddress http://127.0.0.1:9292/bidcos http://$remoteip:9292/bidcos
10/31/19 19:28:31.444 Debug: waitForCorrectTime set to 1
10/31/19 19:28:31.444 Debug: prioritizeThreads set to 1
10/31/19 19:28:31.444 Debug: secureMemorySize set to 65536
10/31/19 19:28:31.444 Debug: workerThreadWindow set to 3000
10/31/19 19:28:31.444 Debug: scriptEngineMaxThreadsPerScript set to 4
10/31/19 19:28:31.444 Debug: scriptEngineMaxScriptsPerProcess set to 50
10/31/19 19:28:31.444 Debug: scriptEngineThreadCount set to 5
10/31/19 19:28:31.444 Debug: scriptEngineServerMaxConnections set to 10
10/31/19 19:28:31.444 Debug: scriptEngineWatchdogTimeout set to 60000
10/31/19 19:28:31.444 Debug: scriptEngineManualClientStart set to 0
10/31/19 19:28:31.444 Debug: nodeBlueProcessingThreadCountServer set to 5
10/31/19 19:28:31.444 Debug: nodeBlueProcessingThreadCountNodes set to 10
10/31/19 19:28:31.444 Debug: nodeBlueServerMaxConnections set to 50
10/31/19 19:28:31.444 Debug: maxNodeThreadsPerProcess set to 60
10/31/19 19:28:31.444 Debug: nodeBlueWatchdogTimeout set to 60000
10/31/19 19:28:31.444 Debug: nodeBlueManualClientStart set to 0
10/31/19 19:28:31.444 Debug: ipcThreadCount set to 5
10/31/19 19:28:31.444 Debug: ipsServerMaxConnections set to 20
10/31/19 19:28:31.444 Debug: cliServerMaxConnections set to 50
10/31/19 19:28:31.444 Debug: rpcServerMaxConnections set to 50
10/31/19 19:28:31.444 Debug: rpcServerThreadPriority set to 0
10/31/19 19:28:31.444 Debug: rpcServerThreadPolicy set to 0
10/31/19 19:28:31.444 Debug: rpcClientMaxThreads set to 50
10/31/19 19:28:31.444 Debug: rpcClientThreadPriority set to 0
10/31/19 19:28:31.444 Debug: rpcClientThreadPolicy set to 0
10/31/19 19:28:31.444 Debug: workerThreadPriority set to 0
10/31/19 19:28:31.444 Debug: workerThreadPolicy set to 0
10/31/19 19:28:31.444 Debug: physicalInterfaceThreadPriority set to 45
10/31/19 19:28:31.444 Debug: physicalInterfaceThreadPolicy set to 1
10/31/19 19:28:31.444 Debug: packetReceivedThreadPriority set to 0
10/31/19 19:28:31.445 Debug: packetReceivedThreadPolicy set to 0
10/31/19 19:28:31.445 Debug: eventThreadCount set to 5
10/31/19 19:28:31.445 Debug: eventThreadPriority set to 0
10/31/19 19:28:31.445 Debug: eventThreadPolicy set to 0
10/31/19 19:28:31.445 Loading RPC server settings from /etc/homegear/rpcservers.conf
10/31/19 19:28:31.445 Debug: familyServer of server FamilyRPCServer set to 1
10/31/19 19:28:31.445 Debug: interface of server FamilyRPCServer set to ::1
10/31/19 19:28:31.445 Debug: port of server FamilyRPCServer set to 2000
10/31/19 19:28:31.445 Debug: xmlrpcServer of server FamilyRPCServer set to 1
10/31/19 19:28:31.445 Debug: jsonrpcServer of server FamilyRPCServer set to 1
10/31/19 19:28:31.445 Debug: ssl of server FamilyRPCServer set to 0
10/31/19 19:28:31.445 Debug: authType of server FamilyRPCServer set to 1
10/31/19 19:28:31.445 Debug: interface of server RPCServer1 set to ::
10/31/19 19:28:31.445 Debug: port of server RPCServer1 set to 2001
10/31/19 19:28:31.445 Debug: xmlrpcServer of server RPCServer1 set to 1
10/31/19 19:28:31.445 Debug: jsonrpcServer of server RPCServer1 set to 1
10/31/19 19:28:31.445 Debug: webServer of server RPCServer1 set to 1
10/31/19 19:28:31.445 Debug: restServer of server RPCServer1 set to 1
10/31/19 19:28:31.445 Debug: contentPath of RPC server RPCServer1 set to /var/lib/homegear/www/rpc/
10/31/19 19:28:31.446 Debug: webSocket of server RPCServer1 set to 1
10/31/19 19:28:31.446 Debug: ssl of server RPCServer1 set to 0
10/31/19 19:28:31.446 Debug: authType of server RPCServer1 set to 1
10/31/19 19:28:31.446 Debug: webSocketAuthType of server RPCServer1 set to 8
10/31/19 19:28:31.446 Debug: cacheAssets of server RPCServer1 set to 2592000
10/31/19 19:28:31.446 Debug: interface of server RPCServer2 set to ::
10/31/19 19:28:31.446 Debug: port of server RPCServer2 set to 2002
10/31/19 19:28:31.446 Debug: xmlrpcServer of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: jsonrpcServer of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: webServer of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: restServer of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: contentPath of RPC server RPCServer2 set to /var/lib/homegear/www/rpc/
10/31/19 19:28:31.446 Debug: webSocket of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: ssl of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: authType of server RPCServer2 set to 1
10/31/19 19:28:31.446 Debug: webSocketAuthType of server RPCServer2 set to 8
10/31/19 19:28:31.446 Debug: caPath of server RPCServer2 set to /etc/homegear/ca/cacert.pem
10/31/19 19:28:31.446 Debug: certPath of server RPCServer2 set to /etc/homegear/homegear.crt
10/31/19 19:28:31.446 Debug: keyPath of server RPCServer2 set to /etc/homegear/homegear.key
10/31/19 19:28:31.446 Debug: dhParamPath of server RPCServer2 set to /etc/homegear/dh1024.pem
10/31/19 19:28:31.446 Debug: cacheAssets of server RPCServer2 set to 2592000
10/31/19 19:28:31.446 Debug: interface of server RPCServer3 set to ::
10/31/19 19:28:31.446 Debug: port of server RPCServer3 set to 2003
10/31/19 19:28:31.446 Debug: xmlrpcServer of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: jsonrpcServer of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: webServer of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: restServer of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: contentPath of RPC server RPCServer3 set to /var/lib/homegear/www/rpc/
10/31/19 19:28:31.446 Debug: webSocket of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: ssl of server RPCServer3 set to 1
10/31/19 19:28:31.446 Debug: authType of server RPCServer3 set to 6
10/31/19 19:28:31.446 Debug: webSocketAuthType of server RPCServer3 set to 8
10/31/19 19:28:31.446 Debug: caPath of server RPCServer3 set to /etc/homegear/ca/cacert.pem
10/31/19 19:28:31.446 Debug: certPath of server RPCServer3 set to /etc/homegear/homegear.crt
10/31/19 19:28:31.446 Debug: keyPath of server RPCServer3 set to /etc/homegear/homegear.key
10/31/19 19:28:31.447 Debug: dhParamPath of server RPCServer3 set to /etc/homegear/dh1024.pem
10/31/19 19:28:31.447 Debug: cacheAssets of server RPCServer3 set to 2592000
10/31/19 19:28:31.447 Debug: cacheAssets of server RPCServer3 set to 2592000
10/31/19 19:28:31.447 Debug: cacheAssets of server RPCServer3 set to 2592000
10/31/19 19:28:31.447 Loading RPC client settings from /etc/homegear/rpcclients.conf
10/31/19 19:28:31.447 Debug: hostname of RPC client ExampleClient1 set to myhostname
10/31/19 19:28:31.447 Debug: caFile of RPC client ExampleClient1 set to /path/to/ca-certficate
10/31/19 19:28:31.447 Debug: verifyCertificate of RPC client ExampleClient1 set to 1
10/31/19 19:28:31.447 Debug: forceSSL of RPC client ExampleClient1 set to 0
10/31/19 19:28:31.447 Debug: authType of RPC client ExampleClient1 set to 1
10/31/19 19:28:31.447 Debug: userName of RPC client ExampleClient1 set to myuser
10/31/19 19:28:31.447 Debug: password of RPC client ExampleClient1 was set.
10/31/19 19:28:31.447 Debug: certFile of RPC client ExampleClient1 set to /path/to/client.crt
10/31/19 19:28:31.447 Debug: keyFile of RPC client ExampleClient1 set to /path/to/client.key
10/31/19 19:28:31.447 Debug: retries of RPC client ExampleClient1 set to 3
10/31/19 19:28:31.447 Debug: timeout of RPC client ExampleClient1 set to 15000000
10/31/19 19:28:31.447 Debug: hostname of RPC client ExampleClient2 set to 192.168.178.89
10/31/19 19:28:31.447 Debug: retries of RPC client ExampleClient2 set to 5
10/31/19 19:28:31.447 Debug: timeout of RPC client ExampleClient2 set to 30000000
10/31/19 19:28:31.448 Debug (MQTT settings): enabled set to 0
10/31/19 19:28:31.448 Debug (MQTT settings): brokerHostname set to localhost
10/31/19 19:28:31.448 Debug (MQTT settings): brokerPort set to 1883
10/31/19 19:28:31.448 Debug (MQTT settings): clientName set to Homegear
10/31/19 19:28:31.448 Debug (MQTT settings): prefix set to homegear/
10/31/19 19:28:31.448 Debug (MQTT settings): homegearId set to 1234-5678-9abc
10/31/19 19:28:31.448 Debug (MQTT settings): retain set to 1
10/31/19 19:28:31.448 Debug (MQTT settings): processingThreadCount set to 5
10/31/19 19:28:31.448 Debug (MQTT settings): plainTopic set to 1
10/31/19 19:28:31.448 Debug (MQTT settings): jsonTopic set to 1
10/31/19 19:28:31.448 Debug (MQTT settings): jsonobjTopic set to 1
10/31/19 19:28:31.448 Debug (MQTT settings): enableSSL set to
10/31/19 19:28:31.448 Info: Setting allowed core file size to "4294967295" for user with id 0 and group with id 0.
10/31/19 19:28:31.448 Info: Core file size now is "4294967295".
10/31/19 19:28:31.448 Info: Setting maximum thread priority to "0" for user with id 0 and group with id 0.
10/31/19 19:28:31.448 Info: Maximum thread priority now is "0".
10/31/19 19:28:31.449 Starting Homegear...
10/31/19 19:28:31.449 Homegear version 0.7.40-2948
10/31/19 19:28:31.449 Git commit SHA of libhomegear-base: -
10/31/19 19:28:31.449 Git branch of libhomegear-base:     -
10/31/19 19:28:31.449 Git commit SHA of Homegear:         -
10/31/19 19:28:31.449 Git branch of Homegear:             -
10/31/19 19:28:31.450 Determining maximum thread count...
10/31/19 19:28:32.668 Maximum thread count is: 218
10/31/19 19:28:32.700 Info: Backing up database...
10/31/19 19:28:34.123 Initializing database...
10/31/19 19:28:34.131 Debug: Loading licensing modules
10/31/19 19:28:34.132 Initializing system variable controller...
10/31/19 19:28:34.134 Debug: Loading family modules
10/31/19 19:28:34.134 Info: Loading family module (type 1) mod_miscellaneous.so
10/31/19 19:28:34.141 Info: Loading settings from /etc/homegear/families/miscellaneous.conf
10/31/19 19:28:34.142 Module Miscellaneous: Debug: Loading module...
10/31/19 19:28:34.142 Info: Loading family module (type 1) mod_homematicbidcos.so
10/31/19 19:28:34.166 Info: Loading settings from /etc/homegear/families/homematicbidcos.conf
10/31/19 19:28:34.166 Debug: Loading section "General"
10/31/19 19:28:34.166 Debug: Family setting moduleenabled set to true
10/31/19 19:28:34.167 Debug: Family setting rfkey set to ABCDEF
10/31/19 19:28:34.167 Debug: Family setting currentrfkeyindex set to 2
10/31/19 19:28:34.167 Debug: Family setting oldrfkey set to ABCDEF
10/31/19 19:28:34.167 Debug: Loading section "HM-CFG-LAN"
10/31/19 19:28:34.167 Debug: id set to My-HM-CFG-LAN
10/31/19 19:28:34.167 Debug: default set to 1
10/31/19 19:28:34.168 Debug: deviceType set to hmcfglan
10/31/19 19:28:34.168 Debug: host set to 192.168.169.4
10/31/19 19:28:34.168 Debug: port set to 1000

Die Homematicbidcons.conf sieht so aus:

[General]

moduleEnabled = true

## The BidCoS address of Homegear; it is recommended to change this to a random 3-byte hexadecimal
## value starting with 0xFD (e. g. 0xFD43AB). Change this only if no HomeMatic BidCoS devices
## are paired to Homegear because existing pairings will not work anymore!
#centralAddress = 0xFD0001

## Here, specify an AES key that is 16 bytes (32 characters) long to protect your wireless communication.
## !!! IMPORTANT: We highly recommend changing this key before pairing the first device.
## !!! IMPORTANT: Never lose this key. Without it, your devices will be useless.
## To remove the key from your devices, you need to factory reset them.
rfKey = ABCD

## With each key change, currentRFKeyIndex needs to be
## incremented by 1.
currentRFKeyIndex = 2

## If you change rfKey, put the old key here. In order to change the key, Homegear needs to know both the
## old key and the new one.
## !!! Do not set oldRFKey when you set rfKey for the first time !!!
oldRFKey = ABCD

[HM-CFG-LAN]
id = My-HM-CFG-LAN
default = true
deviceType = hmcfglan
host = 192.168.169.4
port = 1000
lanKey = 892E57AC0F00C82FC87aaaaaaFE63512
responseDelay = 60

Wenn ich den Prozess logge, sehe ich folgenden Fehler:

Thread 4 (Thread 0x723ff140 (LWP 30062)):
#0  futex_wait_cancelable (private=0, expected=0, futex_word=0x2629c10) at ../sysdeps/unix/sysv/linux/futex-internal.h:88
#1  __pthread_cond_wait_common (abstime=0x0, mutex=0x0, cond=0x2629be8) at pthread_cond_wait.c:502
#2  __pthread_cond_wait (cond=0x2629be8, mutex=0x0) at pthread_cond_wait.c:655
#3  0x75b12c94 in __gthread_cond_wait (__mutex=<optimized out>, __cond=__cond@entry=0x2629be8)
    at /build/gcc-8-aLIRar/gcc-8-8.3.0/build/arm-linux-gnueabihf/libstdc++-v3/include/arm-linux-gnueabihf/bits/gthr-default.h:864
#4  std::condition_variable::wait (this=this@entry=0x2629be8, __lock=...) at ../../../../../src/libstdc++-v3/src/c++11/condition_variable.cc:53
#5  0x76b38be8 in std::condition_variable::wait<BaseLib::IQueue::process(int32_t)::<lambda()> > (__p=..., __lock=..., this=<optimized out>) at /usr/include/c++/8/bits/atomic_base.h:390
#6  BaseLib::IQueue::process (this=0x262bfb0, index=0) at IQueue.cpp:155
#7  0x75b189b0 in std::execute_native_thread_routine (__p=0x262c4c0) at ../../../../../src/libstdc++-v3/src/c++11/thread.cc:80
#8  0x76eef494 in start_thread (arg=0x723ff140) at pthread_create.c:486
Backtrace stopped: Cannot access memory at address 0x626368f8

Thread 3 (Thread 0x72dc8140 (LWP 29817)):
#0  0x758aad00 in __GI___sigtimedwait (set=set@entry=0x72dc7a98, info=info@entry=0x72dc799c, timeout=timeout@entry=0x0) at ../sysdeps/unix/sysv/linux/sigtimedwait.c:29
#1  0x76efb5d4 in __sigwait (set=0x72dc7a98, set@entry=0x72dc7a90, sig=0x72dc7a4c, sig@entry=0x72dc7a44) at ../sysdeps/unix/sysv/linux/sigwait.c:28
#2  0x0019167c in signalHandlerThread () at main.cpp:333
#3  0x75b189b0 in std::execute_native_thread_routine (__p=0x260aee0) at ../../../../../src/libstdc++-v3/src/c++11/thread.cc:80
#4  0x76eef494 in start_thread (arg=0x72dc8140) at pthread_create.c:486
#5  0x75955578 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:73 from /lib/arm-linux-gnueabihf/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)

Thread 2 (Thread 0x735c9140 (LWP 29816)):
#0  0x758aad00 in __GI___sigtimedwait (set=0x735c8a10, set@entry=0x76dc2c98 <BaseLib::ProcessManager::OpaquePointer::_stopSignalHandlerThread>, info=0x735c8a90, timeout=0x735c89ec,
    timeout@entry=0x735c89e4) at ../sysdeps/unix/sysv/linux/sigtimedwait.c:29
#1  0x76c6ca30 in BaseLib::ProcessManager::OpaquePointer::signalHandler () at Managers/ProcessManager.cpp:84
#2  0x75b189b0 in std::execute_native_thread_routine (__p=0x260ae50) at ../../../../../src/libstdc++-v3/src/c++11/thread.cc:80
#3  0x76eef494 in start_thread (arg=0x735c9140) at pthread_create.c:486
#4  0x75955578 in ?? () at ../sysdeps/unix/sysv/linux/arm/clone.S:73 from /lib/arm-linux-gnueabihf/libc.so.6
Backtrace stopped: previous frame identical to this frame (corrupt stack?)

Thread 1 (Thread 0x735fd420 (LWP 29811)):
#0  std::__cxx11::basic_string<char, std::char_traits<char>, std::allocator<char> >::operator[] (__pos=<optimized out>, this=<optimized out>) at /usr/include/c++/8/bits/basic_string.h:1046
#1  BaseLib::Rpc::JsonDecoder::decodeNumber (json="HIERSTEHTDERLANKEY", pos=@0x7edac830: 5, value=<error reading variable: Cannot access memory at address 0x4>)
    at Encoding/JsonDecoder.cpp:967
#2  0x76c11214 in BaseLib::Rpc::JsonDecoder::decodeValue (json="HIERSTEHTDERLANKEY", pos=@0x7edac830: 5, value=
    std::shared_ptr<class BaseLib::Variable> (use count 1, weak count 0) = {...}) at Encoding/JsonDecoder.cpp:788
#3  0x76c12634 in BaseLib::Rpc::JsonDecoder::decode (json="HIERSTEHTDERLANKEY") at Encoding/JsonDecoder.cpp:50
#4  0x76cd64f0 in BaseLib::Systems::FamilySettings::processStringSetting (this=this@entry=0x2646d48, name="lankey", value="HIERSTEHTDERLANKEY",
    settings=std::shared_ptr<class BaseLib::Systems::PhysicalInterfaceSettings> (use count 1, weak count 0) = {...}) at Systems/FamilySettings.cpp:432
#5  0x76cde52c in BaseLib::Systems::FamilySettings::load (this=0x7edac9ac, this@entry=0x2646d48, filename="/etc/homegear/families/homematicbidcos.conf") at Systems/FamilySettings.cpp:364
#6  0x76ce9c4c in BaseLib::Systems::IDeviceFamily::IDeviceFamily (this=0x264cb48, bl=0x10fb750 <std::cout>, eventHandler=<optimized out>, id=82, name="HomeMatic BidCoS",
    type=BaseLib::Systems::FamilyType::sharedObject) at /usr/include/c++/8/bits/basic_string.h:936
#7  0x76cd3714 in BaseLib::Systems::DeviceFamily::DeviceFamily (this=0x264cb48, bl=0x26278a8, eventHandler=<optimized out>, id=0, name="HomeMatic BidCoS")
    at /usr/include/c++/8/bits/basic_string.h:936
#8  0x71a4eaa4 in BidCoS::BidCoS::BidCoS (this=0x264cb48, bl=0x26278a8, eventHandler=<optimized out>) at /usr/include/c++/8/bits/basic_string.h:252
#9  0x71a3fcb0 in BidCoSFactory::createDeviceFamily (this=<optimized out>, bl=0x26278a8, eventHandler=0x262bf20) at Factory.cpp:36
#10 0x002268f4 in Homegear::ModuleLoader::createModule (eventHandler=0x7edad0a0, this=<optimized out>) at /usr/include/c++/8/bits/unique_ptr.h:342

Ich hoffe ihr könnt mir weiterhelfen. Würde nämlich zumindest gerne meine Homematic-Geräte sauber “ablernen”.

Vielen Dank!

sathya · November 2, 2019, 1:31pm

Hallo @seb,

vielen Dank für die ausführlichen Infos. Ich habe dadurch das Problem bei mir reproduzieren können und schaue, woran es liegt.

Viele Grüße

Sathya

sathya · November 2, 2019, 1:42pm

Sollte im nächsten Nightly behoben sein . Vielen Dank!

seb · November 2, 2019, 6:35pm

Wow, vielen Dank! Werde es testen und dann berichten.

Besten Dank