Jun 02 23:13:00 volumio-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 23:13:00 volumio-room go-librespot[1552]: go-librespot daemon starting... Jun 02 23:13:00 volumio-room RoonBridge[1533]: 00:00:00.018 Warn: get lock file path: /tmp/.rnbgem1000- Jun 02 23:13:00 volumio-room sudo[1547]: pam_unix(sudo:session): session closed for user root Jun 02 23:13:00 volumio-room volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Jun 02 23:13:00 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:13:00 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:13:00 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:13:00 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:13:00 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:00 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:00 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:13:00 volumio-room volumio5-onboarding[1495]: time=2026-06-02T23:13:00.432+02:00 level=INFO msg="system info for cf4004cdf4a463cd4dbbcd8770edf44b" deviceName="Volumio room" deviceVariant=volumio deviceModel= softwareVersion=4.119 Jun 02 23:13:00 volumio-room volumio5-onboarding[1495]: time=2026-06-02T23:13:00.464+02:00 level=INFO msg="bootstrapping state" hasInternet=true Jun 02 23:13:00 volumio-room volumio[1048]: info: No need to fix Spotify hosts Jun 02 23:13:00 volumio-room RoonBridge[1533]: 00:00:00.943 Trace: [childprocess] using unix child process Jun 02 23:13:00 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:00 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:00 volumio-room RoonBridge[1533]: 00:00:01.083 Debug: PathForResource, filename: ../.update Jun 02 23:13:01 volumio-room RoonBridge[1533]: 00:00:02.022 Debug: PathForResource, candidate: /opt/roonbridge/Bridge/../.update Jun 02 23:13:01 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:13:01 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:13:01 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:13:01 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:13:01 volumio-room RoonBridge[1533]: 00:00:02.041 Debug: PathForResource, filename: RAATServer Jun 02 23:13:01 volumio-room RoonBridge[1533]: 00:00:02.041 Debug: PathForResource, candidate: /opt/roonbridge/Bridge/RAATServer Jun 02 23:13:01 volumio-room RoonBridge[1533]: 00:00:02.043 Debug: PathForResource, filename: RoonBridgeHelper Jun 02 23:13:01 volumio-room RoonBridge[1533]: 00:00:02.043 Debug: PathForResource, candidate: /opt/roonbridge/Bridge/RoonBridgeHelper Jun 02 23:13:01 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:01 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:01 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:13:01 volumio-room RoonBridge[1533]: Initializing Jun 02 23:13:01 volumio-room go-librespot[1553]: time="2026-06-02T23:13:01+02:00" level=info msg="running go-librespot 0.7.1" Jun 02 23:13:01 volumio-room go-librespot[1553]: time="2026-06-02T23:13:01+02:00" level=debug msg="app state loaded" Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 23:13:02 volumio-room RoonBridge[1533]: 00:00:02.209 Info: Starting /opt/roonbridge/Bridge/RoonBridgeHelper Jun 02 23:13:02 volumio-room RoonBridge[1533]: 00:00:02.253 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/roonbridge/Bridge/RAATServer Jun 02 23:13:02 volumio-room volumio[1048]: -> REQUEST 0 com.roonlabs.registry:1/info Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 02 23:13:02 volumio-room RoonBridge[1533]: Not Running (.o) Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=info msg="zeroconf server listening on port 33343" Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Jun 02 23:13:02 volumio-room go-librespot[1553]: time="2026-06-02T23:13:02+02:00" level=debug msg="obtained new client token: AABZ2W8bKlpjaITnSLvsfYPrJFMr4X6lhWZrWinZB9KvW19faLVm2e3a7m42rQms6qOpiDQa5/Oo3scAgNniqvlVE+IcQckETFHn4kUfYz0lUfuiqKDWXkfv/bcyP3xBSQ6PeD7twYNYcLnLiV+mJOHAPYE4PrU9EAsIk5c15B6/yjWhh81CGVgALHuM8Y/mzibQ9uvgs9MxA8c5k3PUNFTtDqxpa8wrqcUdqyLafigGwvAulV0x6gY=" Jun 02 23:13:02 volumio-room volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="completed keyexchange" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="completed challenge" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=info msg="authenticated AP" username="fo***89" Jun 02 23:13:03 volumio-room volumio-remote-updater[633]: Test mode enabled Jun 02 23:13:03 volumio-room volumio-remote-updater[633]: Alpha mode disabled Jun 02 23:13:03 volumio-room volumio-remote-updater[633]: Alpha legacy test mode disabled Jun 02 23:13:03 volumio-room volumio[1048]: <- COMPLETE 0 Success {"core_id":"94087967-4694-4e8e-973f-fa4a867ad5da","display_name":"dogflute","display_version":"2.66 (build 1658) production"} Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=info msg="authenticated Login5" username="fo***89" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="initializing zeroconf session" username="fo***89" Jun 02 23:13:03 volumio-room volumio[1048]: -> REQUEST 1 com.roonlabs.registry:1/register {"extension_id":"com.volumio.metaroon","display_name":"Volumio Roon Bridge","display_version":"1.0.0","publisher":"Volumio Community","email":"plugins@volumio.com","required_services":["com.roonlabs.transport:2","com.roonlabs.image:1"],"optional_services":["com.roonlabs.browse:1"],"provided_services":["com.roonlabs.volumecontrol:1","com.roonlabs.pairing:1","com.roonlabs.ping:1"]} Jun 02 23:13:03 volumio-room volumio[1048]: info: New Spotify access tokenBQAUG61ime... Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="dealer connection opened" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=trace msg="starting accesspoint recv loop" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=trace msg="starting dealer recv loop" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=trace msg="received accesspoint ping" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="received connection id: ZGRlMmRhZjQtM2E2...NjNFQzUyRDUyOQ==" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 02 23:13:03 volumio-room RoonBridge[1533]: Running Jun 02 23:13:03 volumio-room volumio[1048]: info: Spotify credentials grant success - running version from March 24, 2019 Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="put connect state because NEW_DEVICE" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 02 23:13:03 volumio-room go-librespot[1553]: time="2026-06-02T23:13:03+02:00" level=trace msg="received accesspoint pong ack" Jun 02 23:13:03 volumio-room RoonBridge[1568]: 00:00:00.057 Warn: get lock file path: /tmp/.rnbhgem1000- Jun 02 23:13:04 volumio-room volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 02 23:13:04 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 02 23:13:04 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:13:04 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:13:04 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 23:13:04 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 23:13:04 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 23:13:05 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:13:05 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:13:05 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:13:05 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:13:05 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:05 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:05 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:13:05 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 23:13:05 volumio-room volumio5-onboarding[1495]: time=2026-06-02T23:13:05.699+02:00 level=INFO msg="enabling local network discovery" Jun 02 23:13:05 volumio-room volumio5-onboarding[1495]: time=2026-06-02T23:13:05.801+02:00 level=INFO msg="enabling BLE discovery" Jun 02 23:13:06 volumio-room volumio[1048]: info: go-librespot daemon successfully initialized Jun 02 23:13:06 volumio-room volumio[1048]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jun 02 23:13:06 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:06 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:06 volumio-room volumio[1048]: SPOTIFY: User informations: {"account_id":"l3BD8ls1Gp","country":"DK","display_name":"forte89","email":"forte89@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/forte89"},"followers":{"href":null,"total":17},"href":"https://api.spotify.com/v1/users/forte89","id":"forte89","images":[],"product":"free","type":"user","uri":"spotify:user:forte89"} Jun 02 23:13:06 volumio-room volumio[1048]: info: Spotify Successfully logged in Jun 02 23:13:06 volumio-room volumio[1048]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 23:13:06 volumio-room volumio[1048]: info: [1780434786818] CoreMusicLibrary::Adding element Spotify Jun 02 23:13:06 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 23:13:06 volumio-room volumio[1048]: Cannot find translation for source SoundCloud Jun 02 23:13:06 volumio-room volumio[1048]: Cannot find translation for source Spotify Jun 02 23:13:06 volumio-room volumio5-onboarding[1495]: time=2026-06-02T23:13:06.963+02:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 02 23:13:08 volumio-room volumio[1048]: info: Adding plugin bluetooth to MyMusic Plugins Jun 02 23:13:08 volumio-room volumio[1048]: info: Adding plugin multiroom to MyMusic Plugins Jun 02 23:13:08 volumio-room volumio[1048]: info: Adding plugin metavolumio to MyMusic Plugins Jun 02 23:13:08 volumio-room volumio[1048]: info: Adding plugin cd_controller to MyMusic Plugins Jun 02 23:13:09 volumio-room volumio[1048]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 02 23:13:09 volumio-room volumio[1048]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 02 23:13:09 volumio-room volumio[1048]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 02 23:13:09 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 02 23:13:30 volumio-room volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 02 23:13:30 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 02 23:13:30 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:30 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:30 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:30 volumio-room volumio[1048]: info: Starting MyVolumio Remote Streaming Endpoints Jun 02 23:13:30 volumio-room volumio[1048]: info: MyVolumio login type: Token Jun 02 23:13:30 volumio-room volumio[1048]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 02 23:13:30 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 02 23:13:33 volumio-room go-librespot[1553]: time="2026-06-02T23:13:33+02:00" level=trace msg="sent dealer ping" Jun 02 23:13:33 volumio-room go-librespot[1553]: time="2026-06-02T23:13:33+02:00" level=trace msg="received dealer pong" Jun 02 23:13:39 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 02 23:13:39 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 02 23:13:39 volumio-room volumio[1048]: info: Streaming services startup Jun 02 23:13:39 volumio-room volumio[1048]: info: Starting Streaming Daemon Jun 02 23:13:39 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 02 23:13:40 volumio-room volumio[1048]: info: Initializing connection to go-librespot Websocket Jun 02 23:13:40 volumio-room sudo[1686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 23:13:40 volumio-room sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:13:40 volumio-room volumio[1048]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 02 23:13:40 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:40 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 23:13:40 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:40 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 23:13:40 volumio-room volumio[1048]: MOO: empty message received Jun 02 23:13:41 volumio-room sudo[1686]: pam_unix(sudo:session): session closed for user root Jun 02 23:13:42 volumio-room volumio[1048]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jun 02 23:13:42 volumio-room go-librespot[1553]: time="2026-06-02T23:13:42+02:00" level=debug msg="new websocket client" Jun 02 23:13:42 volumio-room volumio[1048]: error: Cannot start Volumio Streaming Daemon Jun 02 23:13:42 volumio-room volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 23:13:42 volumio-room volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 23:13:42 volumio-room volumio[1048]: info: Connection to go-librespot Websocket established Jun 02 23:13:42 volumio-room volumio[1048]: -> REQUEST 0 com.roonlabs.registry:1/info Jun 02 23:13:42 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:42 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:42 volumio-room volumio[1048]: <- COMPLETE 0 Success {"core_id":"94087967-4694-4e8e-973f-fa4a867ad5da","display_name":"dogflute","display_version":"2.66 (build 1658) production"} Jun 02 23:13:42 volumio-room volumio[1048]: -> REQUEST 1 com.roonlabs.registry:1/register {"extension_id":"com.volumio.metaroon","display_name":"Volumio Roon Bridge","display_version":"1.0.0","publisher":"Volumio Community","email":"plugins@volumio.com","required_services":["com.roonlabs.transport:2","com.roonlabs.image:1"],"optional_services":["com.roonlabs.browse:1"],"provided_services":["com.roonlabs.volumecontrol:1","com.roonlabs.pairing:1","com.roonlabs.ping:1"]} Jun 02 23:13:42 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:13:42 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:13:43 volumio-room volumio[1048]: error: MyVolumio Custom Token format not valid, refreshing it Jun 02 23:13:44 volumio-room volumio-remote-updater[633]: Test mode enabled Jun 02 23:13:44 volumio-room volumio-remote-updater[633]: Alpha mode disabled Jun 02 23:13:44 volumio-room volumio-remote-updater[633]: Alpha legacy test mode disabled Jun 02 23:13:44 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 02 23:13:44 volumio-room volumio[1048]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 02 23:13:44 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 02 23:13:45 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 23:13:45 volumio-room volumio[1048]: info: Getting Spotify volume Jun 02 23:13:45 volumio-room volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 02 23:13:45 volumio-room volumio[1048]: info: Spotify volume: 100 Jun 02 23:13:45 volumio-room volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 02 23:13:46 volumio-room volumio[1048]: info: MyVolumio login type: Token Jun 02 23:13:46 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:46 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:46 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:46 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:46 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Jun 02 23:13:46 volumio-room volumio[1048]: SPOTIFY: SPOTIFY VOLUME 100 Jun 02 23:13:46 volumio-room volumio[1048]: SPOTIFY: VOLUMIO VOLUME 30 Jun 02 23:13:46 volumio-room volumio[1048]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 02 23:13:46 volumio-room volumio[1048]: info: Setting Spotify Volume from Volumio: 30 Jun 02 23:13:47 volumio-room volumio[1048]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 02 23:13:47 volumio-room volumio[1048]: SPOTIFY: SETTING SPOTIFY VOLUME 30 Jun 02 23:13:47 volumio-room volumio[1048]: info: Sending Spotify command with payload to local API: /player/volume Jun 02 23:13:47 volumio-room volumio[1048]: error: Failed to send command to Spotify local API: /player/volume: Error: Bad Request Jun 02 23:13:49 volumio-room volumio[1048]: info: MyVolumio token set successfully Jun 02 23:13:49 volumio-room volumio[1048]: info: MYVOLUMIO: Adding device Jun 02 23:13:49 volumio-room volumio[1048]: info: MYVOLUMIO: Evaluating Server Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetBrowseSources Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 23:13:50 volumio-room volumio[1048]: info: MyVolumio Plan changed: premium Jun 02 23:13:50 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 02 23:13:50 volumio-room volumio[1048]: info: Removing browser output: myVolumio user plan is not superstar Jun 02 23:13:50 volumio-room volumio[1048]: info: Removing audio output: Jun 02 23:13:50 volumio-room volumio[1048]: info: MYVOLUMIO: Adding device Jun 02 23:13:50 volumio-room volumio[1048]: info: MYVOLUMIO: Evaluating Server Jun 02 23:13:50 volumio-room volumio[1048]: info: Setting Geolocation for MyVolumio to eu10 Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:50 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: Updating MyVolumio device info Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: Remote config written successfully Jun 02 23:13:52 volumio-room volumio[1048]: info: Starting Tunnel 1 Jun 02 23:13:52 volumio-room volumio[1048]: info: Starting Tunnel Connection Checker Jun 02 23:13:52 volumio-room volumio[1048]: info: Completed starting MyVolumio Plugin Jun 02 23:13:52 volumio-room volumio[1048]: info: Setting Geolocation for MyVolumio to eu7 Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:52 volumio-room volumio[1048]: info: MYVolumio Device enabled Jun 02 23:13:52 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 02 23:13:52 volumio-room volumio[1048]: info: MyVolumio status changed Jun 02 23:13:52 volumio-room volumio[1048]: info: Streaming services startup Jun 02 23:13:52 volumio-room volumio[1048]: info: Starting Streaming Daemon Jun 02 23:13:52 volumio-room volumio[1048]: info: Successfully Updated MyVolumio device Jun 02 23:13:53 volumio-room sudo[1762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 02 23:13:53 volumio-room sudo[1762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:13:53 volumio-room sudo[1762]: pam_unix(sudo:session): session closed for user root Jun 02 23:13:53 volumio-room volumio[1048]: info: Successfully Added MyVolumio device Jun 02 23:13:53 volumio-room volumio[1048]: info: Updating MyVolumio device info Jun 02 23:13:53 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:53 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:53 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:53 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:53 volumio-room volumio[1048]: error: Cannot start Volumio Streaming Daemon Jun 02 23:13:54 volumio-room volumio[1048]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 02 23:13:54 volumio-room volumio[1048]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 02 23:13:54 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 02 23:13:54 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:13:54 volumio-room volumio[1048]: error: [MyVolumio PluginManager] Cache data is invalid! Jun 02 23:13:54 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:13:54 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:13:54 volumio-room volumio[1048]: info: Successfully Added MyVolumio device Jun 02 23:13:54 volumio-room volumio[1048]: info: Successfully Updated MyVolumio device Jun 02 23:13:55 volumio-room sudo[1266]: pam_unix(sudo:session): session closed for user root Jun 02 23:13:55 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 02 23:14:00 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Jun 02 23:14:00 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Jun 02 23:14:00 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 02 23:14:00 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 02 23:14:03 volumio-room go-librespot[1553]: time="2026-06-02T23:14:03+02:00" level=trace msg="sent dealer ping" Jun 02 23:14:03 volumio-room go-librespot[1553]: time="2026-06-02T23:14:03+02:00" level=trace msg="received dealer pong" Jun 02 23:14:08 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 02 23:14:08 volumio-room sudo[1797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 02 23:14:08 volumio-room sudo[1797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:08 volumio-room sudo[1797]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:09 volumio-room volumio[1048]: info: MRS: MultiRoom plugin initialized Jun 02 23:14:09 volumio-room volumio[1048]: info: MRS: STOPPING SNAPCLIENT Jun 02 23:14:09 volumio-room volumio[1048]: info: MRS: Snap server stop Jun 02 23:14:09 volumio-room volumio[1048]: info: MRS: STOPPING volumioStreaming Jun 02 23:14:09 volumio-room sudo[1815]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 02 23:14:09 volumio-room sudo[1815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:09 volumio-room sudo[1817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 02 23:14:09 volumio-room sudo[1817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:09 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 02 23:14:09 volumio-room sudo[1820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 02 23:14:09 volumio-room sudo[1820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:09 volumio-room sudo[1823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 02 23:14:09 volumio-room sudo[1823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:09 volumio-room sudo[1815]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:09 volumio-room sudo[1817]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:09 volumio-room sudo[1823]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:09 volumio-room sudo[1820]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:10 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 02 23:14:10 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 02 23:14:10 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 02 23:14:10 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 02 23:14:11 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 02 23:14:11 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 02 23:14:11 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 02 23:14:11 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 02 23:14:12 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jun 02 23:14:12 volumio-room volumio[1048]: info: Preparing to generate the ALSA configuration file Jun 02 23:14:13 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 02 23:14:13 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:14:13 volumio-room volumio[1048]: MOO: empty message received Jun 02 23:14:13 volumio-room volumio[1048]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 02 23:14:13 volumio-room volumio[1048]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 02 23:14:13 volumio-room volumio[1048]: info: Reading ALSA contributions from plugins. Jun 02 23:14:13 volumio-room volumio[1048]: info: MRS: Removed streaming files Jun 02 23:14:13 volumio-room volumio[1048]: info: MRS: volumioStreaming STOPPED Jun 02 23:14:13 volumio-room volumio[1048]: info: MRS: SNAPSERVER STOPPED Jun 02 23:14:13 volumio-room volumio[1048]: info: MRS: SNAPCLIENT STOPPED Jun 02 23:14:13 volumio-room volumio[1048]: info: Cannot mount NAS Dogflute at system boot, trial number 1 ,retrying in 5 seconds Jun 02 23:14:13 volumio-room volumio[1048]: -> REQUEST 0 com.roonlabs.registry:1/info Jun 02 23:14:14 volumio-room volumio[1048]: <- COMPLETE 0 Success {"core_id":"94087967-4694-4e8e-973f-fa4a867ad5da","display_name":"dogflute","display_version":"2.66 (build 1658) production"} Jun 02 23:14:14 volumio-room volumio[1048]: -> REQUEST 1 com.roonlabs.registry:1/register {"extension_id":"com.volumio.metaroon","display_name":"Volumio Roon Bridge","display_version":"1.0.0","publisher":"Volumio Community","email":"plugins@volumio.com","required_services":["com.roonlabs.transport:2","com.roonlabs.image:1"],"optional_services":["com.roonlabs.browse:1"],"provided_services":["com.roonlabs.volumecontrol:1","com.roonlabs.pairing:1","com.roonlabs.ping:1"]} Jun 02 23:14:17 volumio-room volumio[1048]: info: Asound.conf file unchanged, so no further update is needed Jun 02 23:14:17 volumio-room volumio[1048]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 23:14:17 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 23:14:17 volumio-room sudo[1853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 02 23:14:17 volumio-room sudo[1853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:17 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 02 23:14:17 volumio-room systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 23:14:17 volumio-room systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 23:14:17 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: [FUNC] onStart Jun 02 23:14:17 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Jun 02 23:14:17 volumio-room systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 02 23:14:17 volumio-room sudo[1853]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:17 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Jun 02 23:14:17 volumio-room autossh[1859]: port set to 0, monitoring disabled Jun 02 23:14:17 volumio-room autossh[1859]: starting ssh (count 1) Jun 02 23:14:17 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Jun 02 23:14:17 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Jun 02 23:14:17 volumio-room autossh[1859]: ssh child pid is 1862 Jun 02 23:14:17 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jun 02 23:14:17 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 02 23:14:17 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output for this device Jun 02 23:14:17 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:17 volumio-room volumio[1048]: info: Adding audio output: Jun 02 23:14:17 volumio-room volumio[1048]: info: Adding audio output: Jun 02 23:14:17 volumio-room volumiossh-tunnel[1862]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Jun 02 23:14:18 volumio-room volumio[1048]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 23:14:18 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 23:14:18 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jun 02 23:14:18 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding METAVOLUMIO REST API Endpoints Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 02 23:14:18 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 02 23:14:18 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 02 23:14:18 volumio-room volumio[1048]: info: Preparing CD Folders Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding CD REST API Endpoints Jun 02 23:14:18 volumio-room volumio[1048]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jun 02 23:14:18 volumio-room volumio[1048]: info: Starting UDEV Watcher for CD Jun 02 23:14:18 volumio-room bluetoothd[622]: Path / reserved for Adv Monitor app :1.21 Jun 02 23:14:18 volumio-room volumio[1048]: info: Detecting CD presence with UDEV Jun 02 23:14:18 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jun 02 23:14:18 volumio-room bluetoothd[622]: Adv Monitor app :1.21 disconnected from D-Bus Jun 02 23:14:24 volumio-room volumio[1048]: warn: [cd-plugin] cdspeedctl: device or media not ready Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding inputs REST Endpoints Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jun 02 23:14:24 volumio-room volumio[1048]: info: Scanning Audio Inputs Jun 02 23:14:24 volumio-room volumio[1048]: info: Checking against Known Cards name Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding Server instance for streaming Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jun 02 23:14:24 volumio-room volumio[1048]: error: Hi Res Audio Failed Login: Missing Login Data Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding HIGHRESAUDIO REST API Endpoints Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 02 23:14:24 volumio-room volumio[1048]: info: Refreshing QOBUZ token Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 02 23:14:24 volumio-room volumio[1048]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jun 02 23:14:24 volumio-room volumio[1048]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jun 02 23:14:24 volumio-room volumio[1048]: info: QobuzConnect: Starting Qobuz Connect socket and service Jun 02 23:14:25 volumio-room sudo[1876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 02 23:14:25 volumio-room sudo[1876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:25 volumio-room volumio[1048]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jun 02 23:14:25 volumio-room sudo[1882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 23:14:25 volumio-room sudo[1882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:25 volumio-room volumio[1048]: info: Adding TIDAL REST API Endpoints Jun 02 23:14:25 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:25 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:26 volumio-room sudo[1882]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:26 volumio-room sudo[1876]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:26 volumio-room volumio[1048]: info: MRS: Getting audio outputs on start Jun 02 23:14:26 volumio-room volumio[1048]: info: MRS: Requesting all other devices output Jun 02 23:14:26 volumio-room volumio[1048]: error: MyVolumio Plugins failed to start in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: error: Plugin audio_interface bluetooth failed to complete 'onStart' in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: error: Plugin music_service qobuzconnect failed to complete 'onStart' in a timely fashion Jun 02 23:14:26 volumio-room volumio[1048]: [Metrics] CommandRouter: 157s 757.93ms Jun 02 23:14:26 volumio-room volumio[1048]: info: CoreCommandRouter::volumiosetStartupVolume Jun 02 23:14:26 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 23:14:26 volumio-room volumio[1048]: info: VolumeController:: Setting startup Volume 40 Jun 02 23:14:26 volumio-room volumio[1048]: info: VolumeController::SetAlsaVolume40 Jun 02 23:14:27 volumio-room sudo[1888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.133:/volume1/music /mnt/NAS/Dogflute Jun 02 23:14:27 volumio-room sudo[1888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::Close All Modals sent Jun 02 23:14:27 volumio-room sudo[1886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::Close All Modals sent Jun 02 23:14:27 volumio-room sudo[1886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:27 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:27 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:27 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:27 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:27 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:27 volumio-room systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jun 02 23:14:27 volumio-room sudo[1886]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:28 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:28 volumio-room volumio[1048]: SPOTIFY: SPOTIFY VOLUME 30 Jun 02 23:14:28 volumio-room volumio[1048]: SPOTIFY: VOLUMIO VOLUME 40 Jun 02 23:14:28 volumio-room volumio[1048]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 02 23:14:28 volumio-room volumio[1048]: info: Setting Spotify Volume from Volumio: 40 Jun 02 23:14:28 volumio-room volumio[1048]: MOO: empty message received Jun 02 23:14:28 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Jun 02 23:14:28 volumio-room volumio[1048]: info: Remote SSH Started Jun 02 23:14:28 volumio-room sudo[1915]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jun 02 23:14:28 volumio-room sudo[1915]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:28 volumio-room systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Jun 02 23:14:29 volumio-room sudo[1915]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:29 volumio-room volumiobt[1918]: INFO [BTSTART] Ensuring Bluetooth directory exists... Jun 02 23:14:29 volumio-room sudo[1919]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Jun 02 23:14:29 volumio-room sudo[1919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:29 volumio-room sudo[1919]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:29 volumio-room sudo[1921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Jun 02 23:14:29 volumio-room sudo[1921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:29 volumio-room sudo[1921]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:29 volumio-room volumiobt[1923]: INFO [BTSTART] Powering on Bluetooth if needed... Jun 02 23:14:29 volumio-room bluetoothd[622]: Path / reserved for Adv Monitor app :1.23 Jun 02 23:14:29 volumio-room volumiobt[1926]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Jun 02 23:14:29 volumio-room bluetoothd[622]: Adv Monitor app :1.23 disconnected from D-Bus Jun 02 23:14:29 volumio-room volumiobt[1927]: [176B blob data] Jun 02 23:14:29 volumio-room volumiobt[1927]: [157B blob data] Jun 02 23:14:29 volumio-room volumiobt[1927]: [157B blob data] Jun 02 23:14:29 volumio-room volumiobt[1927]: [157B blob data] Jun 02 23:14:29 volumio-room volumiobt[1927]: [113B blob data] Jun 02 23:14:29 volumio-room volumiobt[1927]: [bluetoothctl]> discoverable on Jun 02 23:14:29 volumio-room volumiobt[1927]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Jun 02 23:14:29 volumio-room volumiobt[1927]: [bluetoothctl]> pairable on Jun 02 23:14:29 volumio-room bluetoothd[622]: Path / reserved for Adv Monitor app :1.24 Jun 02 23:14:29 volumio-room volumiobt[1927]: [bluetoothctl]> Jun 02 23:14:29 volumio-room volumiobt[1928]: INFO [BTSTART] Registering Bluetooth agent... Jun 02 23:14:29 volumio-room bluetoothd[622]: Adv Monitor app :1.24 disconnected from D-Bus Jun 02 23:14:29 volumio-room volumiobt[1929]: [NEW] Media /org/bluez/hci0 Jun 02 23:14:29 volumio-room volumiobt[1929]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jun 02 23:14:29 volumio-room volumiobt[1929]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jun 02 23:14:29 volumio-room volumiobt[1929]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jun 02 23:14:29 volumio-room bluetoothd[622]: Path / reserved for Adv Monitor app :1.25 Jun 02 23:14:29 volumio-room bluetoothd[622]: Adv Monitor app :1.25 disconnected from D-Bus Jun 02 23:14:29 volumio-room volumiobt[1930]: No agent is registered Jun 02 23:14:29 volumio-room volumiobt[1930]: [NEW] Media /org/bluez/hci0 Jun 02 23:14:29 volumio-room volumiobt[1930]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Jun 02 23:14:29 volumio-room volumiobt[1930]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Jun 02 23:14:29 volumio-room volumiobt[1930]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Jun 02 23:14:30 volumio-room bluetoothd[622]: Path / reserved for Adv Monitor app :1.26 Jun 02 23:14:30 volumio-room bluetoothd[622]: Adv Monitor app :1.26 disconnected from D-Bus Jun 02 23:14:30 volumio-room volumiobt[1931]: INFO [BTSTART] Agent registered successfully. Jun 02 23:14:30 volumio-room volumiobt[1932]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Jun 02 23:14:31 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 02 23:14:31 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 23:14:31 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 02 23:14:31 volumio-room volumio[1048]: SPOTIFY: SETTING SPOTIFY VOLUME 40 Jun 02 23:14:31 volumio-room volumio[1048]: info: Sending Spotify command with payload to local API: /player/volume Jun 02 23:14:31 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:31 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:32 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Jun 02 23:14:32 volumio-room go-librespot[1553]: time="2026-06-02T23:14:32+02:00" level=debug msg="update volume requested to 26214/65535" Jun 02 23:14:32 volumio-room volumio[1048]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Jun 02 23:14:32 volumio-room volumio[1048]: info: TidalConnect service stoped! Jun 02 23:14:32 volumio-room go-librespot[1553]: time="2026-06-02T23:14:32+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 02 23:14:32 volumio-room go-librespot[1553]: time="2026-06-02T23:14:32+02:00" level=trace msg="emitting websocket event: volume" Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Connecting to system D-Bus Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Connected to system D-Bus Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 bluezutils [INFO] Found adapter at: /org/bluez/hci0 Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Set DiscoverableTimeout to infinite Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Enabled Discoverable mode Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Agent registered at /local/a2dpagent Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] Agent set as default Jun 02 23:14:32 volumio-room volumiobt[1933]: 2026-06-02 23:14:32 a2dp-agent [INFO] A2DP agent running, waiting for connections... Jun 02 23:14:33 volumio-room volumio[1048]: info: Executing endpoint qc_getconfig Jun 02 23:14:33 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 23:14:33 volumio-room go-librespot[1553]: time="2026-06-02T23:14:33+02:00" level=trace msg="sent dealer ping" Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.612 [1913.1913] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 23:14:33 volumio-room go-librespot[1553]: time="2026-06-02T23:14:33+02:00" level=trace msg="received dealer pong" Jun 02 23:14:33 volumio-room volumio[1048]: info: Access Token successfully retrieved Jun 02 23:14:33 volumio-room volumio[1048]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 23:14:33 volumio-room volumio[1048]: info: [1780434873712] CoreMusicLibrary::Adding element QOBUZ Jun 02 23:14:33 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 23:14:33 volumio-room volumio[1048]: Cannot find translation for source SoundCloud Jun 02 23:14:33 volumio-room volumio[1048]: Cannot find translation for source Spotify Jun 02 23:14:33 volumio-room volumio[1048]: Cannot find translation for source QOBUZ Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.769 [1913.1913] INFO VolumeManager: [0x7f5f08]: Setting new playback volume: 75 Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.769 [1913.1913] INFO VolumeManager: [0x7f5f08]: Setting new mute state: 0 Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.769 [1913.1913] INFO AudioStreamManager: [0x7f5c60]: Setting new audio download buffer size: 1048576 Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.769 [1913.1913] INFO QobuzConnect: [0x7f67d0]: Client initialized! Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.769 [1913.1913] INFO SampleApp: Starting Avahi advertising, name: Volumio room, service name: _qobuz-connect._tcp Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.805 [1913.1913] INFO LocalConfigManager: [0x7f5988]: Starting Local Configuration server Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.805 [1913.1913] INFO SampleApp: Starting Local configuration server Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.806 [1913.1913] INFO SampleApp: Connected to UNIX socket client 0x7e0818 Jun 02 23:14:33 volumio-room qobuz-connect[1913]: 20260602 23:14:33.806 [1913.1913] INFO SampleApp: Playback volume changed: 75 Jun 02 23:14:33 volumio-room volumio[1048]: info: Stopping AccessToken refresher cron for QOBUZ Jun 02 23:14:33 volumio-room volumio[1048]: info: AccessToken refresher cron started for QOBUZ Jun 02 23:14:34 volumio-room volumio[1048]: info: Adding QOBUZ REST API Endpoints Jun 02 23:14:34 volumio-room volumio[1048]: -> REQUEST 0 com.roonlabs.registry:1/info Jun 02 23:14:34 volumio-room volumio[1048]: SPOTIFY: received: {"type":"volume","data":{"value":40,"max":100}} Jun 02 23:14:34 volumio-room volumio[1048]: SPOTIFY: RECEIVED SPOTIFY VOLUME 40 Jun 02 23:14:34 volumio-room volumio[1048]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 23:14:34 volumio-room volumio[1048]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 23:14:34 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:34 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:34 volumio-room sudo[1956]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 02 23:14:34 volumio-room sudo[1956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:34 volumio-room volumio[1048]: <- COMPLETE 0 Success {"core_id":"94087967-4694-4e8e-973f-fa4a867ad5da","display_name":"dogflute","display_version":"2.66 (build 1658) production"} Jun 02 23:14:34 volumio-room sudo[1958]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 02 23:14:34 volumio-room volumio[1048]: -> REQUEST 1 com.roonlabs.registry:1/register {"extension_id":"com.volumio.metaroon","display_name":"Volumio Roon Bridge","display_version":"1.0.0","publisher":"Volumio Community","email":"plugins@volumio.com","required_services":["com.roonlabs.transport:2","com.roonlabs.image:1"],"optional_services":["com.roonlabs.browse:1"],"provided_services":["com.roonlabs.volumecontrol:1","com.roonlabs.pairing:1","com.roonlabs.ping:1"]} Jun 02 23:14:34 volumio-room sudo[1956]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:34 volumio-room sudo[1958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:34 volumio-room sudo[1958]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:35 volumio-room volumio[1048]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 02 23:14:35 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:35 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:35 volumio-room volumio[1048]: info: BOOT COMPLETED Jun 02 23:14:35 volumio-room volumio[1048]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 02 23:14:35 volumio-room volumio[1048]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 02 23:14:35 volumio-room volumio[1048]: info: MRS: Found cast device: Chromecast-Ultra-f603e5300dca84c4f857aa412cef6f35 Jun 02 23:14:35 volumio-room volumio[1048]: info: Adding audio output: Jun 02 23:14:35 volumio-room volumio[1048]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.119 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Jun 02 23:14:36 volumio-room sudo[1967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 02 23:14:36 volumio-room sudo[1967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 23:14:36 volumio-room systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 02 23:14:36 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:36 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:36 volumio-room sudo[1967]: pam_unix(sudo:session): session closed for user root Jun 02 23:14:36 volumio-room volumio[1048]: verbose: New Socket.io Connection to 192.168.0.103 from 192.168.0.119 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 23:14:37 volumio-room volumio[1048]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 23:14:37 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:14:37 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 02 23:14:37 volumio-room volumio[1048]: error: Cannot get UI Settings Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 23:14:37 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:14:37 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 23:14:37 volumio-room volumio[1048]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 02 23:14:37 volumio-room volumio[1048]: info: Received Get System Info Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 23:14:37 volumio-room volumio[1048]: info: Discovery: Getting this device information Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 23:14:37 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:37 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:38 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:38 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 23:14:38 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 02 23:14:39 volumio-room volumio[1048]: info: Executing endpoint tc_getconfig Jun 02 23:14:39 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 02 23:14:39 volumio-room vtcs[1971]: STARTING TidalConnect services, version: 1.6.1 Jun 02 23:14:39 volumio-room vtcs[1971]: [2026-06-02 23:14:39.680] [tisoc] [critical] [DeviceInfo.cpp:52] ioctl error. ret=-1, errno="No such device" Jun 02 23:14:39 volumio-room vtcs[1971]: [2026-06-02 23:14:39.680] [tisoc] [critical] [DeviceInfo.cpp:53] [ASSERT] generateDeviceId(0) Jun 02 23:14:39 volumio-room vtcs[1971]: STARTED TidalConnect services. Jun 02 23:14:39 volumio-room volumio[1048]: info: TidalConnect service started! Jun 02 23:14:39 volumio-room volumio[1048]: info: Executing endpoint tc_connect Jun 02 23:14:39 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 02 23:14:39 volumio-room volumio[1048]: info: Connecting to TidalConnect Jun 02 23:14:39 volumio-room volumio[1048]: info: CoreCommandRouter::servicePushState Jun 02 23:14:39 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:39 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:39 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jun 02 23:14:40 volumio-room volumio[1048]: verbose: CURRENT POSITION 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::syncState stateService stop Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: No code Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::servicePushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jun 02 23:14:40 volumio-room volumio[1048]: verbose: CURRENT POSITION 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::syncState stateService stop Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::syncState currentStatus stop Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: No code Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreStateMachine::pushState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioPushState Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output update for this device Jun 02 23:14:40 volumio-room volumio[1048]: info: MRS: Pushing multiroomSync output Jun 02 23:14:40 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:40 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:41 volumio-room volumio[1048]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 02 23:14:43 volumio-room volumio[1048]: info: CoreCommandRouter::volumioGetState Jun 02 23:14:43 volumio-room volumio[1048]: info: CorePlayQueue::getTrack 0 Jun 02 23:14:43 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:43 volumio-room volumio[1048]: info: Listing playlists Jun 02 23:14:45 volumio-room volumio[1048]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 02 23:14:45 volumio-room volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 23:14:45 volumio-room volumio[1048]: TypeError: Cannot read properties of undefined (reading 'then') Jun 02 23:14:45 volumio-room volumio[1048]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:2027:20) Jun 02 23:14:45 volumio-room volumio[1048]: at Socket.emit (node:events:514:28) Jun 02 23:14:45 volumio-room volumio[1048]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 02 23:14:45 volumio-room volumio[1048]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jun 02 23:14:45 volumio-room volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 23:14:59 volumio-room sudo[2062]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 23:13' Jun 02 23:14:59 volumio-room sudo[2062]: 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"