Service timeout on Raspberry Pi Zero 2 W #1051

Webguyatwork · 2022-02-01T21:39:26Z

Webguyatwork
Feb 1, 2022

I've successfully gotten Room Assistant set up on my Home Assistant as the cluster lead node, and one of my RPi Zero 2 W devices as a secondary. Now that I've moved onto a 3rd instance on another RPi Zero 2 W, the service is failing to start up all the way. It checks into MQTT and appears inside of Home Assistant, but seems to bounce up and down randomly.

Unable to start service room-assistant: Job for room-assistant.service failed because a timeout was exceeded. See "systemctl status room-assistant.service" and "journalctl -xe" for details.

Note the "activating (start)" status. On the working Pi, this shows as "active (running)".

$ sudo systemctl status room-assistant
● room-assistant.service - room-assistant service
     Loaded: loaded (/etc/systemd/system/room-assistant.service; enabled; vendor preset: enabled)
     Active: activating (start) since Tue 2022-02-01 21:09:20 GMT; 5s ago
   Main PID: 16923 (node)
      Tasks: 7 (limit: 409)
        CPU: 6.329s
     CGroup: /system.slice/room-assistant.service
             └─16923 node /usr/bin/room-assistant

Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Function.Module._load (internal/modules/cjs/loader.js:746:27)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Module.require (internal/modules/cjs/loader.js:974:19)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at require (internal/modules/cjs/helpers.js:101:18)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Object.<anonymous> (/usr/lib/node_modules/room-assistant/node_modules/mdns/lib/dns_sd.js:32:22)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Module._compile (internal/modules/cjs/loader.js:1085:14)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Module.load (internal/modules/cjs/loader.js:950:32)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Function.Module._load (internal/modules/cjs/loader.js:790:12)
Feb 01 21:09:23 homeassist04 room-assistant[16923]:     at Module.require (internal/modules/cjs/loader.js:974:19)
Feb 01 21:09:25 homeassist04 room-assistant[16923]: 01/02/2022, 21:09:25 - info - IntegrationsModule: Loading integrations: home-assistant, bluetooth-classic

Here is my configuration:

bluetoothClassic:
  addresses:
  - B8:B2:F8:EE:66:DD
  - 68:FE:F7:3D:A4:9B
cluster:
  autoDiscovery: false
  networkInterface: wlan0
  peerAddresses:
  - 192.168.86.127:6425
  - 192.168.86.128:6425
  - 192.168.86.132:6425
  port: 6425
  quorum: 2
  timeout: 60
  weight: 10
global:
  instanceName: Bedroom
  integrations:
  - homeAssistant
  - bluetoothClassic
homeAssistant:
  mqttOptions:
    password: '<redacted>'
    username: <redacted>
  mqttUrl: mqtt://192.168.86.127:1883

And the output from 'journalctl -xe':

Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/node_modules/mdns/lib/mdns.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/dist/cluster/cluster.service.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/dist/entities/entities.service.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/dist/entities/entities.module.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/dist/app.module.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/dist/main.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]: - /usr/lib/node_modules/room-assistant/bin/room-assistant.js
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Function.Module._resolveFilename (internal/modules/cjs/loader.js:902:15)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Function.Module._load (internal/modules/cjs/loader.js:746:27)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Module.require (internal/modules/cjs/loader.js:974:19)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at require (internal/modules/cjs/helpers.js:101:18)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Object.<anonymous> (/usr/lib/node_modules/room-assistant/node_modules/mdns/lib/dns_sd.js:32:22)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Module._compile (internal/modules/cjs/loader.js:1085:14)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Object.Module._extensions..js (internal/modules/cjs/loader.js:1114:10)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Module.load (internal/modules/cjs/loader.js:950:32)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Function.Module._load (internal/modules/cjs/loader.js:790:12)
Feb 01 21:31:09 homeassist04 room-assistant[17766]:     at Module.require (internal/modules/cjs/loader.js:974:19)
Feb 01 21:31:10 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:10 - info - IntegrationsModule: Loading integrations: home-assistant, bluetooth-classic
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - NestFactory: Starting Nest application...
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: AppModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: ConfigModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: NestEmitterModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: IntegrationsModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: DiscoveryModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: PrometheusModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: EventsModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: TerminusModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: ClusterModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: ScheduleModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: BluetoothModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: EntitiesModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: BluetoothClassicModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: HomeAssistantModule dependencies initialized
Feb 01 21:31:12 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:12 - info - InstanceLoader: StatusModule dependencies initialized
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - WebSocketsController: EventsGateway subscribed to the "subscribeEvents" message
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RoutesResolver: EntitiesController {/entities}:
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RouterExplorer: Mapped {/entities, GET} route
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RoutesResolver: StatusController {/status}:
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RouterExplorer: Mapped {/status, GET} route
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RoutesResolver: PrometheusController {/metrics}:
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - RouterExplorer: Mapped {/metrics, GET} route
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - ConfigService: Loading configuration from /usr/lib/node_modules/room-assistant/dist/config/definitions/default.js, >
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - ClusterService: bedroom has been elected as leader
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - ClusterService: Added 192.168.86.128:6425 to the cluster with id kitchen
Feb 01 21:31:14 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:14 - info - HomeAssistantService: Successfully connected to MQTT broker at mqtt://192.168.86.127:1883
Feb 01 21:31:15 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:15 - info - NestApplication: Nest application successfully started
Feb 01 21:31:15 homeassist04 room-assistant[17766]: 01/02/2022, 21:31:15 - info - ClusterService: Added 192.168.86.127:6425 to the cluster with id office

Any thoughts on how to get the service fully up and running?