Roonbridge on moode 8x 64bit RPI

tony_smith · September 27, 2022, 3:28pm

hi,

I decided to upgrade the moode os on my pi 4b from 7.x to the latest version of 8.x, all installs fine and works initially, on reboot however the service fails to start and stay persistent. However a manual start of the roonbridge.service brings it back online. Tried “sudo systemctl enable roonbridge.service” and the command works, however again on reboot it ten shows the below when using “systemctl status roonbridge.service”. Output from active status after reboot below…any ideas peops…

roonbridge.service - RoonBridge
Loaded: loaded (/etc/systemd/system/roonbridge.service; disabled; vendor preset: enabled)
Active: inactive (dead)

Sep 27 15:13:43 moodeski start.sh[578]: Initializing
Sep 27 15:13:43 moodeski start.sh[578]: 00:00:00.762 Info: Starting /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 15:13:43 moodeski start.sh[578]: 00:00:00.800 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonBridge/Bridge/RAATServer
Sep 27 15:13:43 moodeski start.sh[578]: Not Running (.o)
Sep 27 15:13:44 moodeski start.sh[734]: 00:00:00.021 Warn: get lock file path: /tmp/.rnbhgem0-
Sep 27 15:13:44 moodeski start.sh[578]: Running
Sep 27 15:13:46 moodeski systemd[1]: Stopping RoonBridge…
Sep 27 15:13:46 moodeski systemd[1]: roonbridge.service: Succeeded.
Sep 27 15:13:46 moodeski systemd[1]: Stopped RoonBridge.
Sep 27 15:13:46 moodeski systemd[1]: roonbridge.service: Consumed 7.645s CPU time.

Jacques_Distler · September 27, 2022, 3:42pm

If you want to run 64bit Raspbian, then you need the 64bit version of Roonbridge

https://download.roonlabs.net/builds/roonbridge-installer-linuxarmv8.sh

ipeverywhere · September 27, 2022, 3:46pm

Run journalctl to get better logs.

My guess is that something is starting early or out of order. No idea what it could be but Roon is starting, expecting something to be there or running and its not so it exits, that thing it was looking for starts, then when you run manually it works fine.

tony_smith · September 27, 2022, 4:05pm

thank Jacques, that’s what I’m running…

tony_smith · September 27, 2022, 4:12pm

hi,

yes, it’s the RAATSever it seems to be waiting on, here is the journalctl output…

Sep 16 23:34:49 moode kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd083]
Sep 16 23:34:49 moode kernel: Linux version 5.15.61-v8+ (dom@buildbot) (aarch64-linux-gnu-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binut>
Sep 16 23:34:49 moode kernel: random: crng init done
Sep 16 23:34:49 moode kernel: Machine model: Raspberry Pi 4 Model B Rev 1.1
Sep 16 23:34:49 moode kernel: efi: UEFI not found.
Sep 16 23:34:49 moode kernel: Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 MiB
Sep 16 23:34:49 moode kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Sep 16 23:34:49 moode kernel: Zone ranges:
Sep 16 23:34:49 moode kernel: DMA [mem 0x0000000000000000-0x000000003fffffff]
Sep 16 23:34:49 moode kernel: DMA32 [mem 0x0000000040000000-0x00000000fbffffff]
Sep 16 23:34:49 moode kernel: Normal empty
Sep 16 23:34:49 moode kernel: Movable zone start for each node
Sep 16 23:34:49 moode kernel: Early memory node ranges
Sep 16 23:34:49 moode kernel: node 0: [mem 0x0000000000000000-0x000000003b3fffff]
Sep 16 23:34:49 moode kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff]
Sep 16 23:34:49 moode kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
Sep 16 23:34:49 moode kernel: On node 0, zone DMA32: 19456 pages in unavailable ranges
Sep 16 23:34:49 moode kernel: On node 0, zone DMA32: 16384 pages in unavailable ranges
Sep 16 23:34:49 moode kernel: percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688
Sep 16 23:34:49 moode kernel: pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*4096
Sep 16 23:34:49 moode kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Sep 16 23:34:49 moode kernel: Detected PIPT I-cache on CPU0
Sep 16 23:34:49 moode kernel: CPU features: detected: Spectre-v2
Sep 16 23:34:49 moode kernel: CPU features: detected: Spectre-v3a
Sep 16 23:34:49 moode kernel: CPU features: detected: Spectre-v4
Sep 16 23:34:49 moode kernel: CPU features: detected: Spectre-BHB
Sep 16 23:34:49 moode kernel: CPU features: kernel page table isolation forced ON by KASLR
Sep 16 23:34:49 moode kernel: CPU features: detected: Kernel page table isolation (KPTI)
Sep 16 23:34:49 moode kernel: CPU features: detected: ARM errata 1165522, 1319367, or 1530923
Sep 16 23:34:49 moode kernel: Built 1 zonelists, mobility grouping on. Total pages: 996912
Sep 16 23:34:49 moode kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_>
Sep 16 23:34:49 moode kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Sep 16 23:34:49 moode kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Sep 16 23:34:49 moode kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Sep 16 23:34:49 moode kernel: software IO TLB: mapped [mem 0x0000000037400000-0x000000003b400000] (64MB)
Sep 16 23:34:49 moode kernel: Memory: 3816052K/4050944K available (11520K kernel code, 1956K rwdata, 4140K rodata, 3840K init, 974K bss, 169356K reserv>
Sep 16 23:34:49 moode kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Sep 16 23:34:49 moode kernel: ftrace: allocating 38040 entries in 149 pages
Sep 16 23:34:49 moode kernel: ftrace: allocated 149 pages with 4 groups
Sep 16 23:34:49 moode kernel: trace event string verifier disabled
Sep 16 23:34:49 moode kernel: rcu: Preemptible hierarchical RCU implementation.
Sep 16 23:34:49 moode kernel: rcu: RCU event tracing is enabled.
Sep 16 23:34:49 moode kernel: rcu: RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
Sep 16 23:34:49 moode kernel: Trampoline variant of Tasks RCU enabled.
Sep 16 23:34:49 moode kernel: Rude variant of Tasks RCU enabled.
Sep 16 23:34:49 moode kernel: Tracing variant of Tasks RCU enabled.
Sep 16 23:34:49 moode kernel: rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.

Then again after a reboot:
root@moodeski:/home/pi# systemctl status roonbridge.service

● roonbridge.service - RoonBridge
Loaded: loaded (/etc/systemd/system/roonbridge.service; disabled; vendor preset: enabled)
Active: inactive (dead)
Sep 27 16:06:39 moodeski start.sh[431]: Initializing
Sep 27 16:06:39 moodeski start.sh[431]: 00:00:00.563 Info: Starting /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 16:06:39 moodeski start.sh[431]: 00:00:00.590 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonBridge/Bridge/RAATServer
Sep 27 16:06:39 moodeski start.sh[431]: Not Running (.o)
Sep 27 16:06:40 moodeski start.sh[678]: 00:00:00.020 Warn: get lock file path: /tmp/.rnbhgem0-
Sep 27 16:06:40 moodeski start.sh[431]: Running
Sep 27 16:06:43 moodeski systemd[1]: Stopping RoonBridge…
Sep 27 16:06:43 moodeski systemd[1]: roonbridge.service: Succeeded.
Sep 27 16:06:43 moodeski systemd[1]: Stopped RoonBridge.
Sep 27 16:06:43 moodeski systemd[1]: roonbridge.service: Consumed 7.884s CPU time.

tony_smith · September 27, 2022, 4:18pm

and when started manually looks like this…

root@moodeski:/home/pi# systemctl status roonbridge.service
● roonbridge.service - RoonBridge
Loaded: loaded (/etc/systemd/system/roonbridge.service; disabled; vendor preset: enabled)
Active: active (running) since Tue 2022-09-27 16:14:22 GMT; 3min 24s ago
Main PID: 1957 (start.sh)
Tasks: 40 (limit: 4471)
CPU: 10.594s
CGroup: /system.slice/roonbridge.service
├─1957 /bin/sh /opt/RoonBridge/start.sh
├─1960 RoonBridge --debug --gc=sgen --server RoonBridge.exe
├─1980 RoonBridgeHelper --debug --gc=sgen --server RoonBridgeHelper.exe
├─1986 /opt/RoonBridge/Bridge/processreaper 1980
└─1990 RAATServer --debug --gc=sgen --server RAATServer.exe

Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.313 Debug: PathForResource, filename: RAATServer
Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.313 Debug: PathForResource, candidate: /opt/RoonBridge/Bridge/RAATServer
Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.314 Debug: PathForResource, filename: RoonBridgeHelper
Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.314 Debug: PathForResource, candidate: /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 16:14:23 moodeski start.sh[1960]: Initializing
Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.341 Info: Starting /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 16:14:23 moodeski start.sh[1960]: 00:00:00.360 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonBridge/Bridge/RAATServer
Sep 27 16:14:23 moodeski start.sh[1960]: Not Running (.o)
Sep 27 16:14:24 moodeski start.sh[1980]: 00:00:00.019 Warn: get lock file path: /tmp/.rnbhgem0-
Sep 27 16:14:24 moodeski start.sh[1960]: Running

tony_smith · September 27, 2022, 5:36pm

ok, so the problem is that on each restart the roonbridge.service autostart at boot reverts to disabled…even when running this:

 root@moodeski:~# sudo systemctl enable roonbridge.service

Created symlink /etc/systemd/system/multi-user.target.wants/roonbridge.service → /etc/systemd/system/roonbridge.service.

then do a status and you get:

oonbridge.service - RoonBridge
Loaded: loaded (/etc/systemd/system/roonbridge.service; enabled; vendor preset: enabled)
Active: active (running) since Tue 2022-09-27 17:31:21 GMT; 3min 32s ago

 then you reboot and you get.......................disabled as below

roonbridge.service - RoonBridge
Loaded: loaded (/etc/systemd/system/roonbridge.service; disabled; vendor preset: enabled)
Active: active (running) since Tue 2022-09-27 17:31:21 GMT; 2s ago
Main PID: 1577 (start.sh)
Tasks: 32 (limit: 4471)
CPU: 5.985s
CGroup: /system.slice/roonbridge.service
├─1577 /bin/sh /opt/RoonBridge/start.sh
├─1580 RoonBridge --debug --gc=sgen --server RoonBridge.exe
├─1593 RoonBridgeHelper --debug --gc=sgen --server RoonBridgeHelper.exe
├─1599 /opt/RoonBridge/Bridge/processreaper 1593
└─1602 RAATServer --debug --gc=sgen --server RAATServer.exe

Sep 27 17:31:21 moodeski start.sh[1580]: 00:00:00.311 Debug: PathForResource, filename: RAATServer
Sep 27 17:31:21 moodeski start.sh[1580]: 00:00:00.311 Debug: PathForResource, candidate: /opt/RoonBridge/Bridge/RAATServer
Sep 27 17:31:21 moodeski start.sh[1580]: 00:00:00.311 Debug: PathForResource, filename: RoonBridgeHelper
Sep 27 17:31:21 moodeski start.sh[1580]: 00:00:00.311 Debug: PathForResource, candidate: /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 17:31:21 moodeski start.sh[1580]: Initializing
Sep 27 17:31:22 moodeski start.sh[1580]: 00:00:00.339 Info: Starting /opt/RoonBridge/Bridge/RoonBridgeHelper
Sep 27 17:31:22 moodeski start.sh[1580]: 00:00:00.358 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonBridge/Bridge/RA>
Sep 27 17:31:22 moodeski start.sh[1580]: Not Running (.o)
Sep 27 17:31:22 moodeski start.sh[1593]: 00:00:00.019 Warn: get lock file path: /tmp/.rnbhgem0-
Sep 27 17:31:22 moodeski start.sh[1580]: Running

Ideas?