Dec 13 15:57:01 volumiozero volumio[1074]: info: MPD Permissions set Dec 13 15:57:01 volumiozero volumio[1074]: info: MPD Permissions set Dec 13 15:57:01 volumiozero mpd[1306]: 2025-12-13T15:57:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 15:57:01 volumiozero systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 15:57:01 volumiozero sudo[1270]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:01 volumiozero sudo[1281]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:02 volumiozero systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 13 15:57:02 volumiozero systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 13 15:57:02 volumiozero systemd[1]: setdatetime-helper.service: Consumed 1.580s CPU time. Dec 13 15:57:02 volumiozero upmpdcli[1368]: writing RSA key Dec 13 15:57:03 volumiozero volumio[1209]: Starting albumart workers Dec 13 15:57:03 volumiozero volumio[1207]: Starting albumart workers Dec 13 15:57:03 volumiozero volumio[1208]: Starting albumart workers Dec 13 15:57:04 volumiozero volumio[1074]: info: Completed starting Core Plugins Dec 13 15:57:04 volumiozero volumio[1074]: info: ------------------------------------------- Dec 13 15:57:04 volumiozero volumio[1074]: info: ----- MyVolumio plugins startup ---- Dec 13 15:57:04 volumiozero volumio[1074]: info: ------------------------------------------- Dec 13 15:57:04 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 13 15:57:04 volumiozero volumio[1074]: info: Volumio called home Dec 13 15:57:07 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223:3000 from 192.168.143.219 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Dec 13 15:57:07 volumiozero volumio[1074]: info: MPD running with PID1306 Dec 13 15:57:07 volumiozero volumio[1074]: ,establishing connection Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero sudo[1376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 15:57:07 volumiozero sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:07 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:08 volumiozero volumio-remote-updater[574]: [2025-12-13 15:57:07] [connect] Successful connection Dec 13 15:57:08 volumiozero systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 15:57:08 volumiozero sudo[1376]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:08 volumiozero mpd_monitor.sh[1388]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 15:57:10 volumiozero volumio[1074]: error: MPD error: The expression evaluated to a falsy value: Dec 13 15:57:10 volumiozero volumio[1074]: assert.ok(self.idling) Dec 13 15:57:10 volumiozero volumio[1074]: error: The expression evaluated to a falsy value: Dec 13 15:57:10 volumiozero volumio[1074]: assert.ok(self.idling) Dec 13 15:57:10 volumiozero volumio[1074]: error: MPD error: The expression evaluated to a falsy value: Dec 13 15:57:10 volumiozero volumio[1074]: assert.ok(self.idling) Dec 13 15:57:10 volumiozero volumio[1074]: error: The expression evaluated to a falsy value: Dec 13 15:57:10 volumiozero volumio[1074]: assert.ok(self.idling) Dec 13 15:57:10 volumiozero volumio[1074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 13 15:57:10 volumiozero sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 15:57:10 volumiozero sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:10 volumiozero sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 13 15:57:10 volumiozero sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:10 volumiozero sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:10 volumiozero systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 15:57:10 volumiozero systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 15:57:10 volumiozero systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 15:57:10 volumiozero systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 15:57:10 volumiozero mpd_monitor.sh[1434]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 15:57:10 volumiozero sudo[1422]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:10 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223:3000 from 192.168.143.219 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Dec 13 15:57:11 volumiozero volumio[1074]: info: Successfully started MPD Monitor Dec 13 15:57:11 volumiozero volumio[1074]: error: updateQueue error: null Dec 13 15:57:11 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223:3000 from 192.168.143.219 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 13 15:57:11 volumiozero volumio[1074]: info: Upmpdcli Daemon Started Dec 13 15:57:11 volumiozero volumio[1074]: info: Successfully started MPD Monitor Dec 13 15:57:11 volumiozero volumio[1074]: info: Starting Shairport Sync Dec 13 15:57:11 volumiozero volumio[1074]: info: Starting Shairport Sync Dec 13 15:57:11 volumiozero volumio[1074]: info: Starting Shairport Sync Dec 13 15:57:11 volumiozero sudo[1441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 15:57:11 volumiozero sudo[1441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:11 volumiozero volumio-remote-updater[574]: [2025-12-13 15:57:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765637827 101 Dec 13 15:57:11 volumiozero systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 15:57:11 volumiozero sudo[1439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 15:57:11 volumiozero sudo[1439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:11 volumiozero systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 15:57:11 volumiozero systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:57:11 volumiozero systemd[1]: shairport-sync.service: Consumed 1.839s CPU time. Dec 13 15:57:11 volumiozero sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 15:57:11 volumiozero volumio[1074]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Dec 13 15:57:11 volumiozero sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:11 volumiozero systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:57:11 volumiozero sudo[1441]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:11 volumiozero volumio[1074]: info: Shairport-Sync Started Dec 13 15:57:11 volumiozero systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 15:57:11 volumiozero systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 15:57:11 volumiozero systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:57:11 volumiozero systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:57:11 volumiozero volumio[1074]: Error adding Membership: Error: addMembership EINVAL Dec 13 15:57:11 volumiozero sudo[1439]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:11 volumiozero volumio[1074]: info: Shairport-Sync Started Dec 13 15:57:11 volumiozero sudo[1444]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:11 volumiozero volumio[1074]: info: Shairport-Sync Started Dec 13 15:57:12 volumiozero volumio[1074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 13 15:57:12 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223:3000 from 192.168.143.219 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 13 15:57:13 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:13 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:13 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:13 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:13 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:13 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:13 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:13 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:14 volumiozero volumio[1074]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin bluetooth to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin multiroom to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin metavolumio to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin cd_controller to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 13 15:57:14 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 13 15:57:25 volumiozero volumio[1074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 13 15:57:25 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 13 15:57:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:25 volumiozero volumio[1074]: info: Starting MyVolumio Remote Streaming Endpoints Dec 13 15:57:25 volumiozero volumio[1074]: info: MyVolumio login type: Token Dec 13 15:57:25 volumiozero volumio[1074]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 13 15:57:25 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 13 15:57:26 volumiozero wpa_supplicant[987]: wlan0: WPA: Group rekeying completed with 74:42:7f:83:ee:08 [GTK=CCMP] Dec 13 15:57:34 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 13 15:57:34 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 13 15:57:34 volumiozero volumio[1074]: info: Streaming services startup Dec 13 15:57:34 volumiozero volumio[1074]: info: Starting Streaming Daemon Dec 13 15:57:34 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 13 15:57:34 volumiozero sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 13 15:57:34 volumiozero sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:34 volumiozero sudo[1498]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:35 volumiozero volumio[1074]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 13 15:57:35 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:35 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 15:57:35 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:35 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 13 15:57:35 volumiozero volumio[1074]: error: Cannot start Volumio Streaming Daemon Dec 13 15:57:35 volumiozero volumio[1074]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 13 15:57:35 volumiozero volumio[1074]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:36 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:36 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 15:57:36 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 13 15:57:37 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 13 15:57:37 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 13 15:57:37 volumiozero sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 13 15:57:37 volumiozero sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1517]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 13 15:57:37 volumiozero sudo[1517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1517]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:37 volumiozero sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 15:57:37 volumiozero sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 13 15:57:37 volumiozero sudo[1512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:37 volumiozero sudo[1527]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:37 volumiozero sudo[1508]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:37 volumiozero sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 13 15:57:37 volumiozero sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 15:57:37 volumiozero sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:37 volumiozero sudo[1533]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:37 volumiozero sudo[1522]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:38 volumiozero volumio-remote-updater[574]: Test mode disabled Dec 13 15:57:38 volumiozero volumio-remote-updater[574]: Alpha mode disabled Dec 13 15:57:38 volumiozero volumio-remote-updater[574]: Alpha legacy test mode disabled Dec 13 15:57:38 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 13 15:57:38 volumiozero volumio[1074]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 13 15:57:38 volumiozero volumio[1074]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 13 15:57:38 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:39 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:39 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:39 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:39 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:39 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:39 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:40 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 15:57:40 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 15:57:40 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:57:40 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223:3000 from 192.168.143.219 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 13 15:57:40 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 15:57:40 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 15:57:40 volumiozero volumio[1074]: info: MyVolumio token set successfully Dec 13 15:57:40 volumiozero volumio[1074]: info: MYVOLUMIO: Adding device Dec 13 15:57:40 volumiozero volumio[1074]: info: MYVOLUMIO: Evaluating Server Dec 13 15:57:41 volumiozero volumio[1074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 13 15:57:41 volumiozero volumio[1074]: info: MyVolumio Plan changed: premium Dec 13 15:57:42 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 13 15:57:42 volumiozero volumio[1074]: info: Removing browser output: myVolumio user plan is not superstar Dec 13 15:57:42 volumiozero volumio[1074]: info: Removing audio output: Dec 13 15:57:42 volumiozero volumio[1074]: info: MYVOLUMIO: Adding device Dec 13 15:57:42 volumiozero volumio[1074]: info: MYVOLUMIO: Evaluating Server Dec 13 15:57:43 volumiozero volumio[1074]: info: Setting Geolocation for MyVolumio to eu10 Dec 13 15:57:43 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:43 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:43 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:43 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:44 volumiozero volumio[1074]: info: Remote config written successfully Dec 13 15:57:44 volumiozero volumio[1074]: info: Starting Tunnel 1 Dec 13 15:57:44 volumiozero volumio[1074]: info: Starting Tunnel Connection Checker Dec 13 15:57:44 volumiozero volumio[1074]: info: Completed starting MyVolumio Plugin Dec 13 15:57:44 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:44 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:44 volumiozero volumio[1074]: info: Updating MyVolumio device info Dec 13 15:57:44 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:44 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:44 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:44 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 13 15:57:45 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 15:57:45 volumiozero sudo[1595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 15:57:45 volumiozero sudo[1597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 15:57:46 volumiozero sudo[1597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:46 volumiozero sudo[1597]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:46 volumiozero sudo[1595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:46 volumiozero sudo[1595]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:46 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223 from 192.168.143.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Dec 13 15:57:47 volumiozero sudo[1604]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 13 15:57:47 volumiozero sudo[1604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:47 volumiozero sudo[1604]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:47 volumiozero sudo[1605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 13 15:57:47 volumiozero sudo[1605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:47 volumiozero sudo[1605]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:47 volumiozero volumio[1074]: verbose: New Socket.io Connection to 192.168.143.223 from 192.168.143.219 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Dec 13 15:57:48 volumiozero volumio[1074]: info: Setting Geolocation for MyVolumio to eu10 Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:48 volumiozero volumio[1074]: info: MYVolumio Device enabled Dec 13 15:57:48 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 13 15:57:48 volumiozero volumio[1074]: info: MyVolumio status changed Dec 13 15:57:48 volumiozero volumio[1074]: info: Streaming services startup Dec 13 15:57:48 volumiozero volumio[1074]: info: Starting Streaming Daemon Dec 13 15:57:48 volumiozero sudo[1611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 13 15:57:48 volumiozero sudo[1611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:57:48 volumiozero sudo[1611]: pam_unix(sudo:session): session closed for user root Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:48 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 13 15:57:48 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 15:57:49 volumiozero volumio[1074]: info: Received Get System Info Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:49 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:49 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:49 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:49 volumiozero volumio[1074]: info: Listing playlists Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:57:49 volumiozero volumio[1074]: info: Successfully Updated MyVolumio device Dec 13 15:57:49 volumiozero volumio[1074]: info: Updating MyVolumio device info Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 15:57:49 volumiozero volumio[1074]: info: Received Get System Info Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:57:49 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:57:49 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:57:49 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:57:50 volumiozero volumio[1074]: info: Successfully Added MyVolumio device Dec 13 15:57:50 volumiozero volumio[1074]: error: Cannot start Volumio Streaming Daemon Dec 13 15:57:50 volumiozero volumio[1074]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 13 15:57:50 volumiozero volumio[1074]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 13 15:57:50 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 13 15:57:50 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:57:50 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 13 15:57:52 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 13 15:57:52 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 13 15:58:01 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 13 15:58:01 volumiozero sudo[1650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 13 15:58:01 volumiozero sudo[1650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:01 volumiozero sudo[1650]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:02 volumiozero volumio[1074]: info: MRS: MultiRoom plugin initialized Dec 13 15:58:02 volumiozero volumio[1074]: info: MRS: STOPPING SNAPCLIENT Dec 13 15:58:02 volumiozero volumio[1074]: info: MRS: Snap server stop Dec 13 15:58:02 volumiozero volumio[1074]: info: MRS: STOPPING volumioStreaming Dec 13 15:58:02 volumiozero sudo[1667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 13 15:58:02 volumiozero sudo[1667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:02 volumiozero sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 13 15:58:02 volumiozero sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:02 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 13 15:58:02 volumiozero sudo[1671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 13 15:58:02 volumiozero sudo[1671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:02 volumiozero sudo[1674]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 13 15:58:02 volumiozero sudo[1674]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:02 volumiozero sudo[1674]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:02 volumiozero sudo[1667]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:02 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 13 15:58:02 volumiozero sudo[1669]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:03 volumiozero sudo[1671]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 13 15:58:03 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 13 15:58:04 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 13 15:58:04 volumiozero volumio[1074]: info: Preparing to generate the ALSA configuration file Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 15:58:05 volumiozero volumio[1074]: info: Received Get System Info Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:58:05 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:58:05 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreStateMachine::getQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CorePlayQueue::getQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: Preload queue cleared Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreStateMachine::ClearQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:05 volumiozero volumio[1074]: info: CorePlayQueue::clearPlayQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CorePlayQueue::saveQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreStateMachine::addQueueItems Dec 13 15:58:05 volumiozero volumio[1074]: info: CorePlayQueue::addQueueItems Dec 13 15:58:05 volumiozero volumio[1074]: info: Preload queue cleared Dec 13 15:58:05 volumiozero volumio[1074]: info: Adding Item to queue: qobuz://song/13102470 Dec 13 15:58:05 volumiozero volumio[1074]: info: Exploding uri qobuz://song/13102470 in service qobuz Dec 13 15:58:05 volumiozero volumio[1074]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 13 15:58:05 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 13 15:58:05 volumiozero volumio[1074]: info: Successfully Updated MyVolumio device Dec 13 15:58:05 volumiozero volumio[1074]: info: MRS: Removed streaming files Dec 13 15:58:05 volumiozero volumio[1074]: info: MRS: volumioStreaming STOPPED Dec 13 15:58:05 volumiozero volumio[1074]: info: MRS: SNAPSERVER STOPPED Dec 13 15:58:05 volumiozero volumio[1074]: info: MRS: SNAPCLIENT STOPPED Dec 13 15:58:06 volumiozero volumio[1074]: error: Failed to add MyVolumio device: undefined Dec 13 15:58:06 volumiozero volumio[1074]: info: Asound.conf file unchanged, so no further update is needed Dec 13 15:58:06 volumiozero volumio[1074]: info: Output device has changed, restarting MPD Dec 13 15:58:06 volumiozero sudo[1686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 13 15:58:06 volumiozero sudo[1686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:06 volumiozero volumio[1074]: info: Output device has changed, restarting Shairport Sync Dec 13 15:58:06 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:06 volumiozero 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. Dec 13 15:58:06 volumiozero 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. Dec 13 15:58:06 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:58:06 volumiozero sudo[1690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 13 15:58:06 volumiozero systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 13 15:58:06 volumiozero sudo[1686]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:06 volumiozero sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:06 volumiozero autossh[1696]: port set to 0, monitoring disabled Dec 13 15:58:06 volumiozero autossh[1696]: starting ssh (count 1) Dec 13 15:58:06 volumiozero autossh[1696]: ssh child pid is 1702 Dec 13 15:58:06 volumiozero sudo[1690]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:06 volumiozero volumio[1074]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 13 15:58:06 volumiozero sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 15:58:06 volumiozero sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:06 volumiozero volumio[1074]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 13 15:58:06 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:06 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 13 15:58:06 volumiozero systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 13 15:58:06 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 13 15:58:07 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 13 15:58:07 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 13 15:58:07 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 13 15:58:07 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 13 15:58:07 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 13 15:58:07 volumiozero volumiossh-tunnel[1702]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 13 15:58:07 volumiozero volumio[1074]: error: Hi Res Audio Failed Login: Missing Login Data Dec 13 15:58:07 volumiozero volumio[1074]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 13 15:58:07 volumiozero volumio[1074]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 13 15:58:07 volumiozero volumio[1074]: info: Refreshing QOBUZ token Dec 13 15:58:07 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 13 15:58:08 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Dec 13 15:58:08 volumiozero volumio[1074]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 13 15:58:08 volumiozero volumio[1074]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 13 15:58:08 volumiozero volumio[1074]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 13 15:58:08 volumiozero volumio[1074]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 13 15:58:08 volumiozero volumio[1074]: info: Adding TIDAL REST API Endpoints Dec 13 15:58:08 volumiozero sudo[1713]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 13 15:58:08 volumiozero sudo[1713]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:08 volumiozero sudo[1713]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:08 volumiozero sudo[1715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 13 15:58:08 volumiozero sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:08 volumiozero bluetoothd[776]: Path / reserved for Adv Monitor app :1.20 Dec 13 15:58:08 volumiozero bluetoothd[776]: Adv Monitor app :1.20 disconnected from D-Bus Dec 13 15:58:08 volumiozero systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 13 15:58:08 volumiozero volumio[1074]: error: error Dec 13 15:58:08 volumiozero volumio[1074]: error: error Dec 13 15:58:08 volumiozero sudo[1715]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:58:09 volumiozero volumio[1074]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 13 15:58:09 volumiozero systemd[1]: mpd.service: Deactivated successfully. Dec 13 15:58:09 volumiozero systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 15:58:09 volumiozero systemd[1]: mpd.service: Consumed 7.899s CPU time. Dec 13 15:58:09 volumiozero systemd[1]: mpd.socket: Deactivated successfully. Dec 13 15:58:09 volumiozero systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 15:58:09 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Connection refused Dec 13 15:58:09 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:396::MPDCli::updStatus: connection failed Dec 13 15:58:09 volumiozero systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 15:58:09 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 13 15:58:09 volumiozero volumio[1074]: info: Received Get System Version Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 15:58:09 volumiozero volumio[1074]: info: Received Get System Info Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:58:09 volumiozero volumio[1074]: info: Discovery: Getting this device information Dec 13 15:58:09 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:58:09 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:10 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:58:10 volumiozero systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 15:58:10 volumiozero systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 15:58:10 volumiozero sudo[1719]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 15:58:10 volumiozero sudo[1719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 15:58:10 volumiozero sudo[1719]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:10 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 13 15:58:10 volumiozero volumio[1074]: info: MPD Permissions set Dec 13 15:58:10 volumiozero volumio[1074]: info: Remote SSH Started Dec 13 15:58:10 volumiozero sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 13 15:58:10 volumiozero sudo[1723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:10 volumiozero systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 13 15:58:11 volumiozero sudo[1723]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:11 volumiozero volumiobt[1732]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 13 15:58:11 volumiozero sudo[1733]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 13 15:58:11 volumiozero sudo[1733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:11 volumiozero sudo[1733]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:11 volumiozero sudo[1739]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 13 15:58:11 volumiozero sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:11 volumiozero sudo[1739]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:11 volumiozero volumiobt[1744]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 13 15:58:11 volumiozero bluetoothd[776]: Path / reserved for Adv Monitor app :1.21 Dec 13 15:58:11 volumiozero bluetoothd[776]: Adv Monitor app :1.21 disconnected from D-Bus Dec 13 15:58:11 volumiozero volumiobt[1750]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 13 15:58:11 volumiozero volumiobt[1751]: [176B blob data] Dec 13 15:58:11 volumiozero volumiobt[1751]: [157B blob data] Dec 13 15:58:11 volumiozero volumiobt[1751]: [157B blob data] Dec 13 15:58:11 volumiozero volumiobt[1751]: [157B blob data] Dec 13 15:58:11 volumiozero volumiobt[1751]: [113B blob data] Dec 13 15:58:11 volumiozero volumiobt[1751]: [bluetoothctl]> discoverable on Dec 13 15:58:11 volumiozero volumiobt[1751]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 13 15:58:11 volumiozero volumiobt[1751]: [bluetoothctl]> pairable on Dec 13 15:58:11 volumiozero bluetoothd[776]: Path / reserved for Adv Monitor app :1.22 Dec 13 15:58:11 volumiozero bluetoothd[776]: Adv Monitor app :1.22 disconnected from D-Bus Dec 13 15:58:11 volumiozero volumiobt[1751]: [bluetoothctl]> Dec 13 15:58:11 volumiozero volumiobt[1752]: INFO [BTSTART] Registering Bluetooth agent... Dec 13 15:58:11 volumiozero volumiobt[1753]: [NEW] Media /org/bluez/hci0 Dec 13 15:58:11 volumiozero volumiobt[1753]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero volumiobt[1753]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero volumiobt[1753]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero bluetoothd[776]: Adv Monitor app :1.23 disconnected from D-Bus Dec 13 15:58:11 volumiozero volumiobt[1754]: No agent is registered Dec 13 15:58:11 volumiozero volumiobt[1754]: [NEW] Media /org/bluez/hci0 Dec 13 15:58:11 volumiozero volumiobt[1754]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero volumiobt[1754]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero volumiobt[1754]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 13 15:58:11 volumiozero bluetoothd[776]: Path / reserved for Adv Monitor app :1.24 Dec 13 15:58:11 volumiozero bluetoothd[776]: Adv Monitor app :1.24 disconnected from D-Bus Dec 13 15:58:12 volumiozero volumiobt[1755]: INFO [BTSTART] Agent registered successfully. Dec 13 15:58:12 volumiozero volumiobt[1756]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 13 15:58:13 volumiozero volumio[1074]: error: error Dec 13 15:58:13 volumiozero volumio[1074]: error: error Dec 13 15:58:13 volumiozero volumio[1074]: error: error Dec 13 15:58:13 volumiozero volumio[1074]: error: error Dec 13 15:58:14 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 13 15:58:14 volumiozero volumio[1074]: info: CoreCommandRouter::volumioStop Dec 13 15:58:14 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:14 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:14 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 13 15:58:14 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:14 volumiozero volumiobt[1757]: INFO:a2dp-agent:Connecting to system bus. Dec 13 15:58:14 volumiozero volumiobt[1757]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 13 15:58:14 volumiozero volumiobt[1757]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 13 15:58:14 volumiozero volumiobt[1757]: INFO:a2dp-agent:Registering agent. Dec 13 15:58:14 volumiozero volumiobt[1757]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 13 15:58:14 volumiozero volumiobt[1757]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 13 15:58:15 volumiozero volumio[1074]: info: Executing endpoint qc_getconfig Dec 13 15:58:15 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 13 15:58:15 volumiozero volumio[1074]: error: MyVolumio Plugins failed to start in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin miscellanea metavolumio failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin music_service cd_controller failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin music_service smart_inputs failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin music_service qobuz failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Dec 13 15:58:15 volumiozero volumio[1074]: [Metrics] CommandRouter: 105s 504.82ms Dec 13 15:58:15 volumiozero volumio[1074]: info: CoreCommandRouter::volumiosetStartupVolume Dec 13 15:58:15 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:15 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:58:15 volumiozero qobuz-connect[1717]: 20251213 15:58:15.814 [1717.1717] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 13 15:58:15 volumiozero volumio[1074]: info: CoreCommandRouter::Close All Modals sent Dec 13 15:58:15 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 13 15:58:15 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:389::MPDCli::updStatus: no connection Dec 13 15:58:15 volumiozero upmpdcli[1329]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::Close All Modals sent Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.022 [1717.1717] INFO VolumeManager: [0x3e6258]: Setting new playback volume: 75 Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.023 [1717.1717] INFO VolumeManager: [0x3e6258]: Setting new mute state: 0 Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.023 [1717.1717] INFO QobuzConnect: [0x3e6c28]: Client initialized! Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.023 [1717.1717] INFO SampleApp: Starting Avahi advertising, name: VolumioZero, service name: _qobuz-connect._tcp Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.059 [1717.1717] INFO LocalConfigManager: [0x3e5b38]: Starting Local Configuration server Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.059 [1717.1717] INFO SampleApp: Starting Local configuration server Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.059 [1717.1717] INFO SampleApp: Playback volume changed: 75 Dec 13 15:58:16 volumiozero qobuz-connect[1717]: 20251213 15:58:16.060 [1717.1717] INFO SampleApp: Connected to UNIX socket client 0x3d0818 Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:58:16 volumiozero volumio[1074]: info: explodeUri took 11180 milliseconds Dec 13 15:58:16 volumiozero volumio[1074]: error: QOBUZ Browse Error: Unauthorized Dec 13 15:58:16 volumiozero volumio[1074]: error: Commandrouter: Cannot explode uri qobuz://song/13102470 from service qobuz: Unauthorized Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:58:16 volumiozero volumio[1074]: info: CorePlayQueue::saveQueue Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::updateTrackBlock Dec 13 15:58:16 volumiozero volumio[1074]: info: CorePlayQueue::getTrackBlock Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPlay Dec 13 15:58:16 volumiozero volumio[1074]: verbose: UNSET VOLATILE: Service: bluetooth Dec 13 15:58:16 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 13 15:58:16 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 13 15:58:16 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::play index 0 Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:16 volumiozero volumio[1074]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::resetVolumioState Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::getcurrentVolume Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioRetrievevolume Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::play index undefined Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::pushState Dec 13 15:58:16 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPushState Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:58:16 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreCommandRouter::volumioStop Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:16 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:16 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:17 volumiozero volumio[1074]: info: Access Token successfully retrieved Dec 13 15:58:17 volumiozero volumio[1074]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 13 15:58:17 volumiozero volumio[1074]: info: [1765637897008] CoreMusicLibrary::Adding element QOBUZ Dec 13 15:58:17 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 15:58:17 volumiozero volumio[1074]: Cannot find translation for source QOBUZ Dec 13 15:58:17 volumiozero volumio[1074]: info: Stopping AccessToken refresher cron for QOBUZ Dec 13 15:58:17 volumiozero volumio[1074]: info: AccessToken refresher cron started for QOBUZ Dec 13 15:58:17 volumiozero volumio[1074]: info: Adding QOBUZ REST API Endpoints Dec 13 15:58:17 volumiozero volumio[1074]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 13 15:58:17 volumiozero volumio[1074]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 13 15:58:17 volumiozero volumio[1074]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 13 15:58:17 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 13 15:58:18 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 15:58:18 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 13 15:58:18 volumiozero mpd[1721]: decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 15:58:18 volumiozero mpd[1721]: output: No 'audio_output' defined in config file Dec 13 15:58:18 volumiozero mpd[1721]: output: Successfully detected a sndio audio device Dec 13 15:58:18 volumiozero mpd[1721]: zeroconf: No global port, disabling zeroconf Dec 13 15:58:18 volumiozero systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 15:58:18 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:58:18 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:18 volumiozero upmpdcli[1329]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 13 15:58:18 volumiozero volumio[1074]: info: Starting Shairport Sync Dec 13 15:58:18 volumiozero sudo[1692]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:18 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 13 15:58:18 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:18 volumiozero volumio[1074]: error: updateQueue error: null Dec 13 15:58:19 volumiozero sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 13 15:58:19 volumiozero sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:19 volumiozero systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 13 15:58:19 volumiozero systemd[1]: shairport-sync.service: Deactivated successfully. Dec 13 15:58:19 volumiozero systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:58:19 volumiozero systemd[1]: shairport-sync.service: Consumed 2.259s CPU time. Dec 13 15:58:19 volumiozero systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 13 15:58:19 volumiozero sudo[1775]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:19 volumiozero volumio[1074]: info: Shairport-Sync Started Dec 13 15:58:21 volumiozero sudo[1802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 15:58:21 volumiozero sudo[1802]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:58:21 volumiozero systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 15:58:21 volumiozero systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 15:58:21 volumiozero systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 15:58:21 volumiozero systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 15:58:21 volumiozero sudo[1802]: pam_unix(sudo:session): session closed for user root Dec 13 15:58:21 volumiozero volumio[1074]: info: Successfully started MPD Monitor Dec 13 15:58:21 volumiozero mpd_monitor.sh[1810]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 15:58:22 volumiozero volumio[1074]: info: Preload queue cleared Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreStateMachine::ClearQueue Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:22 volumiozero volumio[1074]: info: CorePlayQueue::clearPlayQueue Dec 13 15:58:22 volumiozero volumio[1074]: info: CorePlayQueue::saveQueue Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:58:22 volumiozero volumio[1074]: info: CoreStateMachine::addQueueItems Dec 13 15:58:22 volumiozero volumio[1074]: info: CorePlayQueue::addQueueItems Dec 13 15:58:22 volumiozero volumio[1074]: info: Preload queue cleared Dec 13 15:58:22 volumiozero volumio[1074]: info: Adding Item to queue: qobuz://song/13102470 Dec 13 15:58:22 volumiozero volumio[1074]: info: Exploding uri qobuz://song/13102470 in service qobuz Dec 13 15:58:22 volumiozero volumio[1074]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 13 15:58:23 volumiozero volumio[1074]: info: BOOT COMPLETED Dec 13 15:58:23 volumiozero volumio[1074]: info: explodeUri took 366 milliseconds Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:58:23 volumiozero volumio[1074]: info: CorePlayQueue::saveQueue Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::updateTrackBlock Dec 13 15:58:23 volumiozero volumio[1074]: info: CorePlayQueue::getTrackBlock Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreCommandRouter::volumioPlay Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::play index 0 Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::stop Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::play index undefined Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:58:23 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreStateMachine::startPlaybackTimer Dec 13 15:58:23 volumiozero volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 15:58:23 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 15:58:23 volumiozero volumio[1074]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Dec 13 15:58:23 volumiozero volumio[1074]: info: [1765637903564] ControllerQobuz::clearAddPlayTrack Dec 13 15:58:23 volumiozero volumio[1074]: info: getStreamUrl took 331 milliseconds Dec 13 15:58:23 volumiozero volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 15:58:23 volumiozero volumio[1074]: info: sendMpdCommand stop took 27 milliseconds Dec 13 15:58:23 volumiozero volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 15:58:23 volumiozero volumio[1074]: info: Dec 13 15:58:23 volumiozero volumio[1074]: ---------------------------- MPD announces system playlist update Dec 13 15:58:23 volumiozero volumio[1074]: info: Ignoring MPD Status Update Dec 13 15:58:24 volumiozero volumio[1074]: info: sendMpdCommand clear took 77 milliseconds Dec 13 15:58:24 volumiozero volumio[1074]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=2176375&eid=13102470&fmt=6&profile=raw&app_id=539451548&cid=3213579&etsp=1765641503&hmac=ceyyaO1KVmGS6CtWn_2BtJ4_f1E" Dec 13 15:58:24 volumiozero volumio[1074]: error: updateQueue error: null Dec 13 15:58:24 volumiozero volumio[1074]: info: ------------------------------ 53ms Dec 13 15:58:24 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Dec 13 15:58:24 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 13 15:58:24 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: All cached search sources collected Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero mpd[1721]: exception: No such playlist Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , search Dec 13 15:58:25 volumiozero volumio[1074]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:25 volumiozero mpd[1721]: exception: No database Dec 13 15:58:28 volumiozero volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=2176375&eid=13102470&fmt=6&profile=raw&app_id=539451548&cid=3213579&etsp=1765641503&hmac=ceyyaO1KVmGS6CtWn_2BtJ4_f1E" Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: PARSING RESPONSE ERROR Error: Artist:Error: [50@0] {search} No database Dec 13 15:58:28 volumiozero volumio[1074]: info: searchQOBUZUri took 3210 milliseconds Dec 13 15:58:28 volumiozero volumio[1074]: info: All search sources collected, pushing search results Dec 13 15:58:28 volumiozero volumio[1074]: info: CoreCommandRouter::volumioGetQueue Dec 13 15:58:28 volumiozero volumio[1074]: info: CoreStateMachine::getQueue Dec 13 15:58:28 volumiozero volumio[1074]: info: CorePlayQueue::getQueue Dec 13 15:58:28 volumiozero volumio[1074]: info: CoreCommandRouter::volumioAddQueueItems Dec 13 15:58:28 volumiozero volumio[1074]: info: CoreStateMachine::addQueueItems Dec 13 15:58:28 volumiozero volumio[1074]: info: CorePlayQueue::addQueueItems Dec 13 15:58:28 volumiozero volumio[1074]: info: Preload queue cleared Dec 13 15:58:28 volumiozero volumio[1074]: info: Adding Item to queue: qobuz://song/499646 Dec 13 15:58:28 volumiozero volumio[1074]: info: Exploding uri qobuz://song/499646 in service qobuz Dec 13 15:58:28 volumiozero volumio[1074]: https://prod.vlmapi.io/v2/qobuz/explodeUri Dec 13 15:58:28 volumiozero volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:58:29 volumiozero volumio[1074]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 13 15:58:29 volumiozero volumio[1074]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 13 15:58:29 volumiozero volumio[1074]: at new NodeError (node:internal/errors:405:5) Dec 13 15:58:29 volumiozero volumio[1074]: at assert (node:internal/assert:14:11) Dec 13 15:58:29 volumiozero volumio[1074]: at internalConnectMultiple (node:net:1118:3) Dec 13 15:58:29 volumiozero volumio[1074]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 13 15:58:29 volumiozero volumio[1074]: at listOnTimeout (node:internal/timers:575:11) Dec 13 15:58:29 volumiozero volumio[1074]: at process.processTimers (node:internal/timers:514:7) { Dec 13 15:58:29 volumiozero volumio[1074]: code: 'ERR_INTERNAL_ASSERTION' Dec 13 15:58:29 volumiozero volumio[1074]: } Dec 13 15:58:29 volumiozero volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:58:42 volumiozero sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 15:57' Dec 13 15:58:42 volumiozero sudo[1845]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"