May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: magicsock: disco: node [WqqGI] d:4b8a86231f32412f now using 192.168.0.193:41641 mtu=1360 tx=798a954fb770 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: Streaming services startup May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: Starting Streaming Daemon May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 15:48:01 ode-spk-grefsen-03 sudo[11119]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart volumio-streaming-daemon.service May 27 15:48:01 ode-spk-grefsen-03 sudo[11119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connecting to remote: 192.168.0.208 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connecting to remote: 192.168.0.103 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.175 May 27 15:48:01 ode-spk-grefsen-03 sudo[11119]: pam_unix(sudo:session): session closed for user root May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.166 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: error: Cannot start Volumio Streaming Daemon May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] -> POST /api/internal/speaker/events (action=postEvent, speakerId=spk_grefsen_03, eventType=queue_activated) May 27 15:48:01 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (31285ms/60000ms). May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: ssh-conn-20260527T134731-cf1bb25105: starting session: sess-20260527T134801-0d38a3c20d May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: ssh-session(sess-20260527T134801-0d38a3c20d): handling new SSH connection from jakobhn@github (100.119.250.89) to ssh-user "volumio" May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: ssh-session(sess-20260527T134801-0d38a3c20d): access granted to jakobhn@github as ssh-user "volumio" May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: audit: SSH login: user=volumio uid=1000 from=100.119.250.89 ts_user=jakobhn@github node=jakobs-macbook-pro-1.tail56347c.ts.net. May 27 15:48:01 ode-spk-grefsen-03 tailscaled[624]: ssh-session(sess-20260527T134801-0d38a3c20d): starting non-pty command: [/usr/sbin/tailscaled be-child ssh --login-shell=/bin/bash --uid=1000 --gid=1000 --groups=1000,4,7,20,24,25,29,30,44,46,102,103,108,994,993 --local-user=volumio --home-dir=/home/volumio --remote-user=jakobhn@github --remote-ip=100.119.250.89 --has-tty=false --tty-name= --force-v1-behavior --cmd=printf "SERVICES\n"; systemctl is-active ode-anti-pop-keepalive.service || true; systemctl is-enabled ode-anti-pop-keepalive.service || true; systemctl is-active volumio || true; printf "\nPROCESSES\n"; ps -ef | grep -E "aplay|anti-pop|mpd" | grep -v grep || true; printf "\nSTATE\n"; curl -s http://127.0.0.1:3000/api/v1/getState; printf "\n"] May 27 15:48:02 ode-spk-grefsen-03 systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. May 27 15:48:02 ode-spk-grefsen-03 systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... May 27 15:48:02 ode-spk-grefsen-03 systemd-logind[622]: New session c51 of user volumio. May 27 15:48:02 ode-spk-grefsen-03 systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. May 27 15:48:02 ode-spk-grefsen-03 systemd[1]: Starting user@1000.service - User Manager for UID 1000... May 27 15:48:02 ode-spk-grefsen-03 (systemd)[11137]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connecting to remote: 192.168.0.166 May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] <- POST /api/internal/speaker/health 200 (9932ms) (action=postHealthSamples) May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:02 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:02 ode-spk-grefsen-03 systemd[11137]: Queued start job for default target default.target. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Created slice app.slice - User Application Slice. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Reached target paths.target - Paths. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Reached target timers.target - Timers. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Listening on dirmngr.socket - GnuPG network certificate management daemon. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Reached target sockets.target - Sockets. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Reached target basic.target - Basic System. May 27 15:48:03 ode-spk-grefsen-03 systemd[1]: Started user@1000.service - User Manager for UID 1000. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Started mpris-proxy.service - Bluetooth mpris proxy. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Reached target default.target - Main User Target. May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: Startup finished in 837ms. May 27 15:48:03 ode-spk-grefsen-03 systemd[1]: Started session-c51.scope - Session c51 of User volumio. May 27 15:48:03 ode-spk-grefsen-03 mpris-proxy[11155]: Can't get on session bus May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE May 27 15:48:03 ode-spk-grefsen-03 systemd[11137]: mpris-proxy.service: Failed with result 'exit-code'. May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:03 ode-spk-grefsen-03 systemd[1]: session-c51.scope: Deactivated successfully. May 27 15:48:03 ode-spk-grefsen-03 tailscaled[624]: ssh-session(sess-20260527T134801-0d38a3c20d): Session complete May 27 15:48:03 ode-spk-grefsen-03 systemd-logind[622]: Session c51 logged out. Waiting for processes to exit. May 27 15:48:03 ode-spk-grefsen-03 systemd-logind[622]: Removed session c51. May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:03 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] <- POST /api/internal/speaker/events 200 (2233ms) (action=postEvent, speakerId=spk_grefsen_03, eventType=queue_activated) May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:04 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:05 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Disconnected from remote: 192.168.0.160 May 27 15:48:05 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:05 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:05 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:05 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Remote connection error: 192.168.0.142 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Remote connection timeout: 192.168.0.142 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:06 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (36345ms/60000ms). May 27 15:48:07 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:07 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:07 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:07 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:08 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:09 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:09 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:09 ode-spk-grefsen-03 tailscaled[624]: control: NetInfo: NetInfo{varies=false ipv6=false ipv6os=false udp=true icmpv4=false derp=#28 portmap=UC link="" firewallmode="ipt-default"} May 27 15:48:09 ode-spk-grefsen-03 volumio[10863]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.166 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 May 27 15:48:09 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:09 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:10 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:10 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:10 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:10 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Remote connection error: 192.168.0.160 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Remote connection timeout: 192.168.0.160 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (41363ms/60000ms). May 27 15:48:11 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Tick heartbeat #51 (duration=30ms, skipped=21, errors=0, potSwitch=true, slideSwitch=true, volumeLocked=false, intentGate=startup, potRaw=302). May 27 15:48:12 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:12 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:12 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:12 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:13 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:13 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: Stopping user@1000.service - User Manager for UID 1000... May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Activating special unit exit.target... May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Stopped target default.target - Main User Target. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Stopped target basic.target - Basic System. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Stopped target paths.target - Paths. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Stopped target sockets.target - Sockets. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Stopped target timers.target - Timers. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Closed dirmngr.socket - GnuPG network certificate management daemon. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Removed slice app.slice - User Application Slice. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Reached target shutdown.target - Shutdown. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Finished systemd-exit.service - Exit the Session. May 27 15:48:13 ode-spk-grefsen-03 systemd[11137]: Reached target exit.target - Exit the Session. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: user@1000.service: Deactivated successfully. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: Stopped user@1000.service - User Manager for UID 1000. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... May 27 15:48:13 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: run-user-1000.mount: Deactivated successfully. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. May 27 15:48:13 ode-spk-grefsen-03 systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. May 27 15:48:13 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:13 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:13 ode-spk-grefsen-03 volumio-remote-updater[627]: Test mode disabled May 27 15:48:13 ode-spk-grefsen-03 volumio-remote-updater[627]: Alpha mode disabled May 27 15:48:13 ode-spk-grefsen-03 volumio-remote-updater[627]: Alpha legacy test mode disabled May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.103 May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:14 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:15 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:15 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:15 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:15 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.142 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.220 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: verbose: New Socket.io Connection to 192.168.0.106:3000 from 192.168.0.166 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:16 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connecting to remote: 192.168.0.160 May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (46493ms/60000ms). May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.121 May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: Retrying Login Due to Network Error May 27 15:48:17 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.166 May 27 15:48:18 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:18 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:18 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:18 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetBrowseSources May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:19 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:20 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:20 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:20 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:20 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: Completed starting MyVolumio Plugin May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: [Metrics] CommandRouter: 62s 62.89ms May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumiosetStartupVolume May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::Close All Modals sent May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::Close All Modals sent May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:21 ode-spk-grefsen-03 tailscaled[624]: magicsock: disco: node [WqqGI] d:4b8a86231f32412f now using 192.168.0.193:41641 mtu=1360 tx=9b49206c568f May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 27 15:48:21 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (51564ms/60000ms). May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:22 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:23 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:23 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:23 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:23 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:23 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.160 May 27 15:48:24 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:24 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:24 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:24 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:24 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.173 May 27 15:48:25 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:25 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:25 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 27 15:48:25 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:25 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:26 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Holding READY during startup guard (56577ms/60000ms). May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:27 ode-spk-grefsen-03 volumio[10863]: info: MyVolumio login type: Token May 27 15:48:28 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:28 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:28 ode-spk-grefsen-03 volumio[10863]: info: BOOT COMPLETED May 27 15:48:28 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:28 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:29 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:30 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:30 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:30 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:30 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:30 ode-spk-grefsen-03 tailscaled[624]: control: NetInfo: NetInfo{varies=true ipv6=false ipv6os=false udp=true icmpv4=false derp=#28 portmap=UC link="" firewallmode="ipt-default"} May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.208 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:31 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] -> GET /api/internal/speaker/settings (speakerId=spk_grefsen_03). May 27 15:48:33 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] <- GET /api/internal/speaker/settings 200 (2191ms, speakerId=spk_grefsen_03). May 27 15:48:34 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:34 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:34 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:34 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:35 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:35 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:35 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Disconnected from remote: 192.168.0.160 May 27 15:48:35 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:35 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] -> POST /api/internal/speaker/events (action=postEvent, speakerId=spk_grefsen_03, eventType=queue_activated) May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:36 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:37 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:37 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:37 ode-spk-grefsen-03 volumio[10863]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 27 15:48:37 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:37 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:38 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:38 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:38 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:38 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:39 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:39 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:39 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:39 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:40 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:40 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:40 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:40 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] <- POST /api/internal/speaker/events 200 (5037ms) (action=postEvent, speakerId=spk_grefsen_03, eventType=queue_activated) May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:41 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Tick heartbeat #107 (duration=27ms, skipped=25, errors=0, potSwitch=true, slideSwitch=true, volumeLocked=false, intentGate=startup, potRaw=302). May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: MyVolumio token set successfully May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: MYVOLUMIO: Adding device May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: MYVOLUMIO: Evaluating Server May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:42 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: MyVolumio Plan changed: premium May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: Removing browser output: myVolumio user plan is not superstar May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: Removing audio output: May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: MYVOLUMIO: Adding device May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: MYVOLUMIO: Evaluating Server May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: Remote config written successfully May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: Starting Tunnel 1 May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: Starting Tunnel Connection Checker May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:43 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Connected to remote: 192.168.0.160 May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: MYVolumio Device enabled May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: MyVolumio status changed May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: Streaming services startup May 27 15:48:44 ode-spk-grefsen-03 volumio[10863]: info: Starting Streaming Daemon May 27 15:48:45 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 27 15:48:45 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:45 ode-spk-grefsen-03 sudo[11275]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart volumio-streaming-daemon.service May 27 15:48:45 ode-spk-grefsen-03 sudo[11275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:45 ode-spk-grefsen-03 sudo[11275]: pam_unix(sudo:session): session closed for user root May 27 15:48:45 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 27 15:48:46 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 27 15:48:46 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 27 15:48:46 ode-spk-grefsen-03 volumio[10863]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 27 15:48:46 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 27 15:48:48 ode-spk-grefsen-03 sudo[11281]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -rf /tmp/multiroom May 27 15:48:48 ode-spk-grefsen-03 sudo[11281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:48 ode-spk-grefsen-03 sudo[11281]: pam_unix(sudo:session): session closed for user root May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: info: MRS: MultiRoom plugin initialized May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: info: MRS: STOPPING SNAPCLIENT May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: info: MRS: Snap server stop May 27 15:48:48 ode-spk-grefsen-03 volumio[10863]: info: MRS: STOPPING volumioStreaming May 27 15:48:48 ode-spk-grefsen-03 sudo[11299]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl stop volumioSnapclient May 27 15:48:48 ode-spk-grefsen-03 sudo[11299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:48 ode-spk-grefsen-03 sudo[11301]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl stop volumioSnapserver May 27 15:48:48 ode-spk-grefsen-03 sudo[11301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:49 ode-spk-grefsen-03 sudo[11303]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl stop volumioStreaming May 27 15:48:49 ode-spk-grefsen-03 sudo[11303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 27 15:48:49 ode-spk-grefsen-03 sudo[11307]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -f /tmp/hls/* May 27 15:48:49 ode-spk-grefsen-03 sudo[11307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:49 ode-spk-grefsen-03 sudo[11307]: pam_unix(sudo:session): session closed for user root May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 27 15:48:49 ode-spk-grefsen-03 sudo[11301]: pam_unix(sudo:session): session closed for user root May 27 15:48:49 ode-spk-grefsen-03 sudo[11299]: pam_unix(sudo:session): session closed for user root May 27 15:48:49 ode-spk-grefsen-03 sudo[11303]: pam_unix(sudo:session): session closed for user root May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: Preparing to generate the ALSA configuration file May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: Reading ALSA contributions from plugins. May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: MRS: Removed streaming files May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: MRS: volumioStreaming STOPPED May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: MRS: SNAPSERVER STOPPED May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: MRS: SNAPCLIENT STOPPED May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: error: Cannot start Volumio Streaming Daemon May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [1779889729824] ControllerTidal::clearAddPlayTrack May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: [ode-speaker-bridge] posted heartbeat via REST_FALLBACK for spk_grefsen_03. May 27 15:48:49 ode-spk-grefsen-03 volumio[10863]: info: Asound.conf file written May 27 15:48:49 ode-spk-grefsen-03 sudo[11318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 27 15:48:49 ode-spk-grefsen-03 sudo[11318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:49 ode-spk-grefsen-03 sudo[11318]: pam_unix(sudo:session): session closed for user root May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Output device has changed, restarting MPD May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:50 ode-spk-grefsen-03 sudo[11324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 15:48:50 ode-spk-grefsen-03 sudo[11324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:50 ode-spk-grefsen-03 sudo[11324]: pam_unix(sudo:session): session closed for user root May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output for this device May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:50 ode-spk-grefsen-03 sudo[11326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 15:48:50 ode-spk-grefsen-03 sudo[11326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:50 ode-spk-grefsen-03 systemd[1]: Stopping mpd.service - Music Player Daemon... May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding METAVOLUMIO REST API Endpoints May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Preparing CD Folders May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding CD REST API Endpoints May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Starting UDEV Watcher for CD May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: Detecting CD presence with UDEV May 27 15:48:50 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 27 15:48:52 ode-spk-grefsen-03 tailscaled[624]: magicsock: disco: node [WqqGI] d:4b8a86231f32412f now using 192.168.0.193:41641 mtu=1360 tx=ded648c4eea0 May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: mpd.service: Deactivated successfully. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: mpd.service: Consumed 7.439s CPU time. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: mpd.socket: Deactivated successfully. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 15:48:54 ode-spk-grefsen-03 systemd[1]: Starting mpd.service - Music Player Daemon... May 27 15:48:54 ode-spk-grefsen-03 sudo[11353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 15:48:54 ode-spk-grefsen-03 sudo[11353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 15:48:54 ode-spk-grefsen-03 sudo[11353]: pam_unix(sudo:session): session closed for user root May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: warn: [cd-plugin] cdspeedctl: device or media not ready May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding inputs REST Endpoints May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Scanning Audio Inputs May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Checking against Known Cards name May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding Server instance for streaming May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: error: Hi Res Audio Failed Login: Missing Login Data May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding HIGHRESAUDIO REST API Endpoints May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Refreshing TIDAL token May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Stopping AccessToken refresher cron for QOBUZ May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: AccessToken refresher cron started for QOBUZ May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding QOBUZ REST API Endpoints May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Getting audio outputs on start May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Requesting all other devices output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Adding multiroomSync output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Adding multiroomSync output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: MRS: Adding multiroomSync output May 27 15:48:55 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MRS: Adding multiroomSync output May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MRS: Adding multiroomSync output May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: Adding audio output: May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MRS: Pushing multiroomSync output May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: MPD Permissions set May 27 15:48:56 ode-spk-grefsen-03 sudo[11361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 27 15:48:56 ode-spk-grefsen-03 sudo[11361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:48:56 ode-spk-grefsen-03 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:56 ode-spk-grefsen-03 autossh[9626]: received signal to exit (15) May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: [1779889736348] ControllerTidal::clearAddPlayTrack May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: Preparing to generate the ALSA configuration file May 27 15:48:56 ode-spk-grefsen-03 systemd[1]: sshtunnel.service: Deactivated successfully. May 27 15:48:56 ode-spk-grefsen-03 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. May 27 15:48:56 ode-spk-grefsen-03 systemd[1]: sshtunnel.service: Consumed 2.932s CPU time. May 27 15:48:56 ode-spk-grefsen-03 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 27 15:48:56 ode-spk-grefsen-03 sudo[11361]: pam_unix(sudo:session): session closed for user root May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: [ode-client] -> PUT /api/internal/speaker/snapshot (action=putSnapshot, speakerId=spk_grefsen_03, queueRevision=song-set:2026-05-27T13:35:04.525Z:window:daytime) May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: Reading ALSA contributions from plugins. May 27 15:48:56 ode-spk-grefsen-03 volumio[10863]: info: Remote SSH Started May 27 15:48:56 ode-spk-grefsen-03 autossh[11374]: port set to 0, monitoring disabled May 27 15:48:56 ode-spk-grefsen-03 autossh[11374]: starting ssh (count 1) May 27 15:48:56 ode-spk-grefsen-03 autossh[11374]: ssh child pid is 11377 May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Asound.conf file written May 27 15:48:57 ode-spk-grefsen-03 sudo[11380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 27 15:48:57 ode-spk-grefsen-03 sudo[11380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 sudo[11380]: pam_unix(sudo:session): session closed for user root May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Output device has changed, restarting MPD May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Preparing to generate the ALSA configuration file May 27 15:48:57 ode-spk-grefsen-03 sudo[11386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 15:48:57 ode-spk-grefsen-03 sudo[11386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 sudo[11389]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: MPD Permissions set May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Reading ALSA contributions from plugins. May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: mpd.service: Deactivated successfully. May 27 15:48:57 ode-spk-grefsen-03 sudo[11389]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 sudo[11386]: pam_unix(sudo:session): session closed for user root May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: mpd.service: Consumed 1.669s CPU time. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: mpd.socket: Deactivated successfully. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Asound.conf file written May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Starting mpd.service - Music Player Daemon... May 27 15:48:57 ode-spk-grefsen-03 sudo[11402]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 27 15:48:57 ode-spk-grefsen-03 sudo[11402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 sudo[11402]: pam_unix(sudo:session): session closed for user root May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Output device has changed, restarting MPD May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 sudo[11408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 27 15:48:57 ode-spk-grefsen-03 sudo[11408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:57 ode-spk-grefsen-03 sudo[11408]: pam_unix(sudo:session): session closed for user root May 27 15:48:57 ode-spk-grefsen-03 sudo[11410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 27 15:48:57 ode-spk-grefsen-03 sudo[11410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: MPD Permissions set May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: mpd.service: Deactivated successfully. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Stopped mpd.service - Music Player Daemon. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: mpd.socket: Deactivated successfully. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: Setting Geolocation for MyVolumio to eu12 May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 27 15:48:57 ode-spk-grefsen-03 systemd[1]: Starting mpd.service - Music Player Daemon... May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:58 ode-spk-grefsen-03 sudo[11421]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 27 15:48:58 ode-spk-grefsen-03 sudo[11421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 27 15:48:58 ode-spk-grefsen-03 sudo[11421]: pam_unix(sudo:session): session closed for user root May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: Setting Geolocation for MyVolumio to eu5 May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: Updating MyVolumio device info May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:58 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: Updating MyVolumio device info May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:48:59 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:00 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:00 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:00 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:00 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: Access Token successfully retrieved May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Playback start intent recorded (startup movement unlock). May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: [ode-hardware-controls] Persisted hardware control intent desiredPower=ON sequence=1. May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: [volumio-rest] -> GET /api/v1/getQueue (action=getQueue) May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetQueue May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::getQueue May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getQueue May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Disconnected from remote: 192.168.0.160 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: Discovery: Disconnected from remote: 192.168.0.160 May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: [volumio-rest] <- GET /api/v1/getQueue 200 (239ms) (action=getQueue) May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: [volumio-rest] -> GET /api/v1/commands/?cmd=play&N=0 (action=playQueuePosition, command=play) May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: info: May 27 15:49:01 ode-spk-grefsen-03 volumio[10863]: ---------------------------- Client requests Volumio play at index 0 May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioGetState May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreCommandRouter::volumioPlay May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index 0 May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::stop May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::play index undefined May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CoreStateMachine::startPlaybackTimer May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: CorePlayQueue::getTrack 0 May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: info: [1779889742005] ControllerTidal::clearAddPlayTrack May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: TypeError: Cannot read properties of undefined (reading 'highestSoundQuality') May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:19570) May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: at ControllerTidal.clearAddPlayTrack (/myvolumio/plugins/music_service/tidal/tidal_real:1:5768) May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: at Promise._successFn (/volumio/app/statemachine.js:1028:24) May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 27 15:49:02 ode-spk-grefsen-03 volumio[10863]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 15:49:02 ode-spk-grefsen-03 volumiossh-tunnel[11377]: Warning: Permanently added '[eu1.myvolumio.org]:2222' (RSA) to the list of known hosts. May 27 15:49:05 ode-spk-grefsen-03 mpd[11423]: 2026-05-27T15:49:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 27 15:49:05 ode-spk-grefsen-03 systemd[1]: Started mpd.service - Music Player Daemon. May 27 15:49:05 ode-spk-grefsen-03 sudo[11389]: pam_unix(sudo:session): session closed for user root May 27 15:49:05 ode-spk-grefsen-03 sudo[11410]: pam_unix(sudo:session): session closed for user root May 27 15:49:05 ode-spk-grefsen-03 sudo[11326]: pam_unix(sudo:session): session closed for user root May 27 15:49:06 ode-spk-grefsen-03 sudo[11454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 15:48' May 27 15:49:06 ode-spk-grefsen-03 sudo[11454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"