Dec 22 00:02:00 volumio volumio-remote-updater[751]: [2025-12-22 00:02:00] [connect] Successful connection Dec 22 00:02:00 volumio volumio[1314]: 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: 4 Dec 22 00:02:00 volumio volumio-remote-updater[751]: [2025-12-22 00:02:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766358120 101 Dec 22 00:02:00 volumio upmpdcli[2094]: writing RSA key Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin bluetooth to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin multiroom to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin metavolumio to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin cd_controller to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 22 00:02:01 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 22 00:02:08 volumio volumio[1314]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 22 00:02:08 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 22 00:02:08 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:08 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:08 volumio volumio[1314]: info: Starting MyVolumio Remote Streaming Endpoints Dec 22 00:02:08 volumio volumio[1314]: info: MyVolumio login type: Token Dec 22 00:02:08 volumio volumio[1314]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 22 00:02:08 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 22 00:02:13 volumio volumio[1314]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 22 00:02:13 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 22 00:02:13 volumio volumio[1314]: info: Streaming services startup Dec 22 00:02:13 volumio volumio[1314]: info: Starting Streaming Daemon Dec 22 00:02:13 volumio volumio[1314]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 22 00:02:14 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:14 volumio volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 22 00:02:14 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:14 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:14 volumio sudo[2210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 22 00:02:14 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:15 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:15 volumio volumio[1314]: error: Cannot start Volumio Streaming Daemon Dec 22 00:02:15 volumio volumio[1314]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 22 00:02:15 volumio volumio[1314]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 22 00:02:16 volumio volumio[1314]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 22 00:02:16 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 00:02:16 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:16 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 00:02:17 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 22 00:02:17 volumio volumio-remote-updater[751]: Test mode disabled Dec 22 00:02:17 volumio volumio-remote-updater[751]: Alpha mode disabled Dec 22 00:02:17 volumio volumio-remote-updater[751]: Alpha legacy test mode disabled Dec 22 00:02:20 volumio volumio[1314]: info: MyVolumio token set successfully Dec 22 00:02:20 volumio volumio[1314]: info: MYVOLUMIO: Adding device Dec 22 00:02:20 volumio volumio[1314]: info: MYVOLUMIO: Evaluating Server Dec 22 00:02:21 volumio volumio[1314]: info: MyVolumio Plan changed: premium Dec 22 00:02:21 volumio volumio[1314]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 22 00:02:21 volumio volumio[1314]: info: Removing browser output: myVolumio user plan is not superstar Dec 22 00:02:21 volumio volumio[1314]: info: Removing audio output: Dec 22 00:02:21 volumio volumio[1314]: info: MYVOLUMIO: Adding device Dec 22 00:02:21 volumio volumio[1314]: info: MYVOLUMIO: Evaluating Server Dec 22 00:02:21 volumio volumio[1314]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 22 00:02:21 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 22 00:02:22 volumio volumio[1314]: info: Setting Geolocation for MyVolumio to eu6 Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: Remote config written successfully Dec 22 00:02:22 volumio volumio[1314]: info: Starting Tunnel 1 Dec 22 00:02:22 volumio volumio[1314]: info: Starting Tunnel Connection Checker Dec 22 00:02:22 volumio volumio[1314]: info: Completed starting MyVolumio Plugin Dec 22 00:02:22 volumio volumio[1314]: info: Setting Geolocation for MyVolumio to eu10 Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:22 volumio volumio[1314]: info: MYVolumio Device enabled Dec 22 00:02:22 volumio volumio[1314]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 22 00:02:22 volumio volumio[1314]: info: MyVolumio status changed Dec 22 00:02:22 volumio volumio[1314]: info: Streaming services startup Dec 22 00:02:22 volumio volumio[1314]: info: Starting Streaming Daemon Dec 22 00:02:23 volumio sudo[2298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 22 00:02:23 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:23 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:23 volumio volumio[1314]: error: Cannot start Volumio Streaming Daemon Dec 22 00:02:23 volumio volumio[1314]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 22 00:02:23 volumio volumio[1314]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 22 00:02:23 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 22 00:02:23 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 22 00:02:25 volumio volumio[1314]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 22 00:02:25 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 22 00:02:35 volumio volumio[1314]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 22 00:02:35 volumio sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 22 00:02:35 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:35 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:35 volumio volumio[1314]: info: MRS: MultiRoom plugin initialized Dec 22 00:02:35 volumio volumio[1314]: info: MRS: STOPPING SNAPCLIENT Dec 22 00:02:35 volumio volumio[1314]: info: MRS: Snap server stop Dec 22 00:02:35 volumio volumio[1314]: info: MRS: STOPPING volumioStreaming Dec 22 00:02:35 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 22 00:02:35 volumio sudo[2362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 22 00:02:35 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:36 volumio sudo[2365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 22 00:02:36 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:36 volumio sudo[2366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 22 00:02:36 volumio sudo[2366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:36 volumio sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 22 00:02:36 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:36 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:36 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:36 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:36 volumio sudo[2366]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:36 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 22 00:02:36 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 22 00:02:36 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 22 00:02:36 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 22 00:02:37 volumio volumio[1314]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 22 00:02:37 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 22 00:02:37 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 22 00:02:37 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 22 00:02:37 volumio volumio[1314]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 22 00:02:37 volumio volumio[1314]: info: Preparing to generate the ALSA configuration file Dec 22 00:02:38 volumio volumio[1314]: info: Updating MyVolumio device info Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: Updating MyVolumio device info Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::volumioGetBrowseSources Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 00:02:38 volumio volumio[1314]: info: Successfully Added MyVolumio device Dec 22 00:02:38 volumio volumio[1314]: info: Successfully Added MyVolumio device Dec 22 00:02:38 volumio volumio[1314]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 00:02:38 volumio volumio[1314]: info: Reading ALSA contributions from plugins. Dec 22 00:02:38 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 22 00:02:38 volumio volumio[1314]: info: MRS: Removed streaming files Dec 22 00:02:38 volumio volumio[1314]: info: MRS: volumioStreaming STOPPED Dec 22 00:02:38 volumio volumio[1314]: info: MRS: SNAPSERVER STOPPED Dec 22 00:02:38 volumio volumio[1314]: info: MRS: SNAPCLIENT STOPPED Dec 22 00:02:38 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:38 volumio volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 22 00:02:39 volumio volumio[1314]: info: Successfully Updated MyVolumio device Dec 22 00:02:39 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 22 00:02:39 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:39 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:39 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 22 00:02:39 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 22 00:02:39 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 22 00:02:39 volumio volumio[1314]: info: Successfully Updated MyVolumio device Dec 22 00:02:39 volumio volumio[1314]: 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 22 00:02:39 volumio volumio[1314]: info: Asound.conf file written Dec 22 00:02:41 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 00:02:41 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:41 volumio sudo[2384]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:42 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 00:02:42 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 00:02:42 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 22 00:02:42 volumio volumio[1314]: info: Output device has changed, restarting MPD Dec 22 00:02:42 volumio volumio[1314]: info: Output device has changed, restarting Shairport Sync Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:42 volumio sudo[2392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 00:02:42 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:42 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:42 volumio sudo[2391]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 00:02:42 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:42 volumio volumio[1314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 00:02:42 volumio volumio[1314]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:42 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 00:02:42 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 22 00:02:42 volumio volumio[1314]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 22 00:02:42 volumio volumio[1314]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 22 00:02:42 volumio volumio[1314]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 22 00:02:42 volumio volumio[1314]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 22 00:02:42 volumio volumio[1314]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 22 00:02:42 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 22 00:02:42 volumio volumio[1314]: info: MRS: Pushing multiroomSync output for this device Dec 22 00:02:42 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:42 volumio volumio[1314]: info: Adding audio output: Dec 22 00:02:42 volumio volumio[1314]: info: Adding audio output: Dec 22 00:02:42 volumio volumio[1314]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:42 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 22 00:02:42 volumio volumio[1314]: info: Adding METAVOLUMIO REST API Endpoints Dec 22 00:02:42 volumio volumio[1314]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 22 00:02:42 volumio volumio[1314]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 22 00:02:42 volumio volumio[1314]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 22 00:02:42 volumio volumio[1314]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 22 00:02:42 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 22 00:02:42 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 22 00:02:42 volumio volumio[1314]: info: Preparing CD Folders Dec 22 00:02:42 volumio volumio[1314]: info: Adding CD REST API Endpoints Dec 22 00:02:42 volumio volumio[1314]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 22 00:02:42 volumio volumio[1314]: info: Starting UDEV Watcher for CD Dec 22 00:02:42 volumio volumio[1314]: info: Detecting CD presence with UDEV Dec 22 00:02:42 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 22 00:02:43 volumio bluetoothd[947]: Path / reserved for Adv Monitor app :1.33 Dec 22 00:02:43 volumio bluetoothd[947]: Adv Monitor app :1.33 disconnected from D-Bus Dec 22 00:02:44 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 00:02:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 00:02:44 volumio systemd[1]: mpd.service: Consumed 4.038s CPU time. Dec 22 00:02:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 00:02:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 00:02:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 00:02:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 00:02:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 00:02:45 volumio sudo[2435]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 00:02:45 volumio sudo[2435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 00:02:45 volumio sudo[2435]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:48 volumio volumio[1314]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 22 00:02:48 volumio mpd[2437]: 2025-12-22T00:02:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 00:02:48 volumio volumio[1314]: info: Adding inputs REST Endpoints Dec 22 00:02:48 volumio volumio[1314]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 22 00:02:48 volumio volumio[1314]: info: Scanning Audio Inputs Dec 22 00:02:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 00:02:48 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:48 volumio volumio[1314]: info: Checking against Known Cards name Dec 22 00:02:48 volumio volumio[1314]: info: Adding Server instance for streaming Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 22 00:02:48 volumio volumio[1314]: error: Hi Res Audio Failed Login: Missing Login Data Dec 22 00:02:48 volumio volumio[1314]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 22 00:02:48 volumio volumio[1314]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 22 00:02:48 volumio volumio[1314]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 22 00:02:48 volumio volumio[1314]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 22 00:02:48 volumio sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:02:48 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:48 volumio volumio[1314]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 22 00:02:48 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:48 volumio sudo[2454]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 00:02:48 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:48 volumio volumio[1314]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 22 00:02:48 volumio volumio[1314]: info: Adding TIDAL REST API Endpoints Dec 22 00:02:48 volumio volumio[1314]: info: Stopping AccessToken refresher cron for QOBUZ Dec 22 00:02:48 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:48 volumio sudo[2456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 00:02:48 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:48 volumio volumio[1314]: info: AccessToken refresher cron started for QOBUZ Dec 22 00:02:48 volumio volumio[1314]: info: Adding QOBUZ REST API Endpoints Dec 22 00:02:48 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:02:48 volumio sudo[2456]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:49 volumio volumio[1314]: info: MRS: Getting audio outputs on start Dec 22 00:02:49 volumio volumio[1314]: info: MRS: Requesting all other devices output Dec 22 00:02:50 volumio volumio[1314]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 22 00:02:50 volumio volumio[1314]: info: MPD Permissions set Dec 22 00:02:50 volumio sudo[2460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 22 00:02:50 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:50 volumio sudo[2462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 22 00:02:50 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:50 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 22 00:02:50 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:50 volumio 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 22 00:02:50 volumio 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 22 00:02:50 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 22 00:02:50 volumio volumiobt[2470]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 22 00:02:50 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:50 volumio sudo[2471]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 22 00:02:50 volumio sudo[2471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:50 volumio sudo[2471]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:50 volumio autossh[2472]: port set to 0, monitoring disabled Dec 22 00:02:50 volumio autossh[2472]: starting ssh (count 1) Dec 22 00:02:50 volumio autossh[2472]: ssh child pid is 2477 Dec 22 00:02:50 volumio sudo[2476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 22 00:02:50 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:50 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:50 volumio volumiobt[2479]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 22 00:02:50 volumio volumio[1314]: info: Executing endpoint qc_getconfig Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 00:02:50 volumio bluetoothd[947]: Adv Monitor app :1.35 disconnected from D-Bus Dec 22 00:02:50 volumio volumiobt[2484]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 22 00:02:50 volumio volumiossh-tunnel[2477]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 22 00:02:50 volumio qobuz-connect[2458]: 20251222 00:02:50.867 [2458.2458] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 00:02:50 volumio volumiobt[2486]: [176B blob data] Dec 22 00:02:50 volumio volumiobt[2486]: [157B blob data] Dec 22 00:02:50 volumio volumiobt[2486]: [157B blob data] Dec 22 00:02:50 volumio volumiobt[2486]: [157B blob data] Dec 22 00:02:50 volumio volumiobt[2486]: [113B blob data] Dec 22 00:02:50 volumio volumiobt[2486]: [bluetoothctl]> discoverable on Dec 22 00:02:50 volumio volumiobt[2486]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 22 00:02:50 volumio volumiobt[2486]: [bluetoothctl]> pairable on Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:50 volumio volumiobt[2486]: [bluetoothctl]> Dec 22 00:02:50 volumio volumiobt[2487]: INFO [BTSTART] Registering Bluetooth agent... Dec 22 00:02:50 volumio bluetoothd[947]: Adv Monitor app :1.36 disconnected from D-Bus Dec 22 00:02:50 volumio volumiobt[2488]: [NEW] Media /org/bluez/hci0 Dec 22 00:02:50 volumio volumiobt[2488]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 22 00:02:50 volumio volumiobt[2488]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 22 00:02:50 volumio volumiobt[2488]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 22 00:02:50 volumio bluetoothd[947]: Path / reserved for Adv Monitor app :1.37 Dec 22 00:02:50 volumio bluetoothd[947]: Adv Monitor app :1.37 disconnected from D-Bus Dec 22 00:02:51 volumio volumiobt[2489]: No agent is registered Dec 22 00:02:51 volumio volumiobt[2489]: [NEW] Media /org/bluez/hci0 Dec 22 00:02:51 volumio volumiobt[2489]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 22 00:02:51 volumio volumiobt[2489]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 22 00:02:51 volumio volumiobt[2489]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.004 [2458.2458] INFO VolumeManager: [0x21e368]: Setting new playback volume: 75 Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.004 [2458.2458] INFO VolumeManager: [0x21e368]: Setting new mute state: 0 Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.004 [2458.2458] INFO QobuzConnect: [0x21ed38]: Client initialized! Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.004 [2458.2458] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 22 00:02:51 volumio bluetoothd[947]: Adv Monitor app :1.38 disconnected from D-Bus Dec 22 00:02:51 volumio volumiobt[2491]: INFO [BTSTART] Agent registered successfully. Dec 22 00:02:51 volumio volumiobt[2492]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.077 [2458.2458] INFO LocalConfigManager: [0x21dc48]: Starting Local Configuration server Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.077 [2458.2458] INFO SampleApp: Starting Local configuration server Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.077 [2458.2458] INFO SampleApp: Connected to UNIX socket client 0x208818 Dec 22 00:02:51 volumio qobuz-connect[2458]: 20251222 00:02:51.078 [2458.2458] INFO SampleApp: Playback volume changed: 75 Dec 22 00:02:51 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:51 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:51 volumio volumio[1314]: error: updateQueue error: null Dec 22 00:02:51 volumio volumio[1314]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 00:02:51 volumio volumio[1314]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 00:02:51 volumio volumio[1314]: info: Remote SSH Started Dec 22 00:02:53 volumio volumiobt[2493]: INFO:a2dp-agent:Connecting to system bus. Dec 22 00:02:53 volumio volumiobt[2493]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 22 00:02:53 volumio volumiobt[2493]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 22 00:02:53 volumio volumiobt[2493]: INFO:a2dp-agent:Registering agent. Dec 22 00:02:53 volumio volumiobt[2493]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 22 00:02:53 volumio volumiobt[2493]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 22 00:02:53 volumio volumio[1314]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::volumioStop Dec 22 00:02:53 volumio volumio[1314]: info: CoreStateMachine::stop Dec 22 00:02:53 volumio volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 00:02:53 volumio volumio[1314]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 22 00:02:53 volumio volumio[1314]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 00:02:53 volumio volumio[1314]: error: MyVolumio Plugins failed to start in a timely fashion Dec 22 00:02:53 volumio volumio[1314]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion Dec 22 00:02:53 volumio volumio[1314]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion Dec 22 00:02:53 volumio volumio[1314]: [Metrics] CommandRouter: 84s 293.04ms Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::volumiosetStartupVolume Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::Close All Modals sent Dec 22 00:02:53 volumio volumio[1314]: info: CoreCommandRouter::Close All Modals sent Dec 22 00:02:53 volumio sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 00:02:53 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:53 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 00:02:53 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 00:02:53 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:54 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 00:02:54 volumio mpd_monitor.sh[2520]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 00:02:54 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:54 volumio volumio[1314]: info: TidalConnect service stoped! Dec 22 00:02:54 volumio volumio[1314]: info: Successfully started MPD Monitor Dec 22 00:02:54 volumio volumio[1314]: info: Starting Shairport Sync Dec 22 00:02:54 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:54 volumio volumio[1314]: info: Listing playlists Dec 22 00:02:54 volumio sudo[2541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 00:02:54 volumio sudo[2541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:54 volumio volumio[1314]: info: Preparing to generate the ALSA configuration file Dec 22 00:02:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 00:02:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 00:02:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:02:54 volumio systemd[1]: shairport-sync.service: Consumed 1.364s CPU time. Dec 22 00:02:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:02:54 volumio sudo[2541]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:54 volumio volumio[1314]: info: MRS: Found cast device: Google-Cast-Group-d528d615f6fb48ab9f957fcf35e8d117-1 Dec 22 00:02:54 volumio volumio[1314]: info: Adding audio output: Dec 22 00:02:54 volumio volumio[1314]: info: MRS: Found cast device: Google-Home-e5df2fdcaf63a0bd6ddf1c655ddac3ad Dec 22 00:02:54 volumio volumio[1314]: info: Adding audio output: Dec 22 00:02:54 volumio volumio[1314]: info: MRS: Found cast device: Google-Home-Mini-06776f5627fde63f61935df416c85549 Dec 22 00:02:54 volumio volumio[1314]: info: Adding audio output: Dec 22 00:02:54 volumio volumio[1314]: info: Shairport-Sync Started Dec 22 00:02:54 volumio volumio[1314]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 00:02:54 volumio volumio[1314]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 22 00:02:54 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:54 volumio volumio[1314]: 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 22 00:02:55 volumio volumio[1314]: info: Asound.conf file written Dec 22 00:02:55 volumio sudo[2563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 00:02:55 volumio sudo[2563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:55 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 22 00:02:55 volumio sudo[2563]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:55 volumio sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 00:02:55 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:56 volumio sudo[2566]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:56 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 00:02:56 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 00:02:56 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 22 00:02:56 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Dec 22 00:02:56 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Dec 22 00:02:56 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Dec 22 00:02:56 volumio volumio[1314]: info: Output device has changed, restarting MPD Dec 22 00:02:56 volumio volumio[1314]: info: Output device has changed, restarting Shairport Sync Dec 22 00:02:56 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:56 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:56 volumio sudo[2579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 00:02:56 volumio sudo[2579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:56 volumio sudo[2577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 00:02:56 volumio sudo[2577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:56 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 00:02:56 volumio volumio[1314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 00:02:56 volumio volumio[1314]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 00:02:56 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:56 volumio sudo[2577]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:56 volumio volumio[1314]: info: QobuzConnect: setDeactiveState invoked Dec 22 00:02:56 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:57 volumio volumio[1314]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2112:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} Dec 22 00:02:57 volumio volumio[1314]: info: Preparing to generate the ALSA configuration file Dec 22 00:02:57 volumio volumio[1314]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 00:02:57 volumio volumio[1314]: info: Reading ALSA contributions from plugins. Dec 22 00:02:57 volumio volumio[1314]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Dec 22 00:02:57 volumio volumio[1314]: info: MPD Permissions set Dec 22 00:02:58 volumio volumio[1314]: info: Executing endpoint tc_getconfig Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 22 00:02:58 volumio vtcs[2569]: STARTING TidalConnect services, version: 1.5.2.56 Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:58 volumio vtcs[2569]: STARTED TidalConnect services. Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:02:58 volumio volumio[1314]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Cgs0vDBsJaXsfmxVdyXqdPm5hzK2.056b91d38ab5de84ea23307385473874.state.status' Dec 22 00:02:58 volumio volumio[1314]: info: Executing endpoint tc_connect Dec 22 00:02:58 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 22 00:02:58 volumio volumio[1314]: info: Connecting to TidalConnect Dec 22 00:02:58 volumio sudo[2590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:02:58 volumio sudo[2596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 00:02:58 volumio sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:58 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:59 volumio volumio[1314]: info: Starting Shairport Sync Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::servicePushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 22 00:02:59 volumio volumio[1314]: 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} Dec 22 00:02:59 volumio volumio[1314]: verbose: CURRENT POSITION 0 Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::syncState stateService stop Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::syncState currentStatus stop Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio volumio[1314]: info: No code Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::servicePushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio volumio[1314]: info: CorePlayQueue::getTrack 0 Dec 22 00:02:59 volumio volumio[1314]: 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} Dec 22 00:02:59 volumio volumio[1314]: verbose: CURRENT POSITION 0 Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::syncState stateService stop Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::syncState currentStatus stop Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio volumio[1314]: info: No code Dec 22 00:02:59 volumio volumio[1314]: info: CoreStateMachine::pushState Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioPushState Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output update for this device Dec 22 00:02:59 volumio volumio[1314]: info: MRS: Pushing multiroomSync output Dec 22 00:02:59 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:02:59 volumio sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 00:02:59 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:59 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 22 00:02:59 volumio systemd[1]: vtcs.service: Killing process 2613 (vtcs) with signal SIGKILL. Dec 22 00:02:59 volumio systemd[1]: vtcs.service: Deactivated successfully. Dec 22 00:02:59 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 22 00:02:59 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:59 volumio sudo[2596]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:59 volumio sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 00:02:59 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:02:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 00:02:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 00:02:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:02:59 volumio systemd[1]: shairport-sync.service: Consumed 1.314s CPU time. Dec 22 00:02:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:02:59 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root Dec 22 00:02:59 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 00:02:59 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 00:02:59 volumio systemd[1]: mpd.service: Consumed 2.858s CPU time. Dec 22 00:02:59 volumio volumio[1314]: info: TidalConnect service started! Dec 22 00:02:59 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 00:02:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 00:02:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 00:02:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 00:02:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 00:02:59 volumio volumio[1314]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Cgs0vDBsJaXsfmxVdyXqdPm5hzK2.056b91d38ab5de84ea23307385473874.state.status' Dec 22 00:02:59 volumio volumio[1314]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Cgs0vDBsJaXsfmxVdyXqdPm5hzK2.056b91d38ab5de84ea23307385473874.state.status' Dec 22 00:02:59 volumio volumio[1314]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Cgs0vDBsJaXsfmxVdyXqdPm5hzK2.056b91d38ab5de84ea23307385473874.state.status' Dec 22 00:02:59 volumio volumio[1314]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Cgs0vDBsJaXsfmxVdyXqdPm5hzK2.056b91d38ab5de84ea23307385473874.state.status' Dec 22 00:02:59 volumio volumio[1314]: info: Shairport-Sync Started Dec 22 00:02:59 volumio volumio[1314]: info: Asound.conf file written Dec 22 00:02:59 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 00:02:59 volumio qobuz-connect[2458]: 20251222 00:02:59.950 [2458.2458] INFO SampleApp: Stopping Local configuration server Dec 22 00:03:00 volumio sudo[2629]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 00:03:00 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 00:03:00 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:00 volumio sudo[2648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 00:03:00 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:00 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:00 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 00:03:00 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 00:03:00 volumio volumio[1314]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 22 00:03:00 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Dec 22 00:03:00 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Dec 22 00:03:00 volumio volumio[1314]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Dec 22 00:03:00 volumio volumio[1314]: info: Output device has changed, restarting MPD Dec 22 00:03:00 volumio sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 00:03:00 volumio sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:00 volumio volumio[1314]: info: Output device has changed, restarting Shairport Sync Dec 22 00:03:00 volumio sudo[2654]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:00 volumio sudo[2657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 00:03:00 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:00 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:00 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:03:00 volumio volumio[1314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 00:03:00 volumio volumio[1314]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 00:03:00 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:00 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 00:03:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 00:03:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 00:03:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 00:03:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 00:03:00 volumio volumio[1314]: info: QobuzConnect: setDeactiveState invoked Dec 22 00:03:00 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:03:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 00:03:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 00:03:00 volumio volumio[1314]: info: Preparing to generate the ALSA configuration file Dec 22 00:03:00 volumio sudo[2668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:03:00 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:00 volumio sudo[2666]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 00:03:00 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 00:03:01 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:01 volumio qobuz-connect[2458]: 20251222 00:03:01.096 [2458.2458] INFO SampleApp: shat down connection on UNIX socket Dec 22 00:03:01 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 00:03:01 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:01 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:01 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:01 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:01 volumio sudo[2675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:03:01 volumio sudo[2675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:01 volumio sudo[2675]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:01 volumio sudo[2683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 00:03:01 volumio sudo[2683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:01 volumio volumio[1314]: info: BOOT COMPLETED Dec 22 00:03:01 volumio sudo[2683]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:01 volumio sudo[2685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 00:03:01 volumio sudo[2685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:01 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 00:03:01 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 00:03:01 volumio volumio[1314]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 00:03:01 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:01 volumio volumio[1314]: info: Reading ALSA contributions from plugins. Dec 22 00:03:01 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:01 volumio sudo[2685]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:02 volumio volumio[1314]: info: MPD Permissions set Dec 22 00:03:02 volumio volumio[1314]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 00:03:02 volumio volumio[1314]: info: QobuzConnect: setDeactiveState invoked Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:03:02 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 22 00:03:02 volumio volumio[1314]: info: TidalConnect service stoped! Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:02 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:03:03 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:03 volumio volumio[1314]: info: Executing endpoint qc_getconfig Dec 22 00:03:03 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 00:03:03 volumio volumio[1314]: info: Executing endpoint qc_getconfig Dec 22 00:03:03 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 00:03:03 volumio volumio[1314]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 00:03:03 volumio volumio[1314]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 00:03:03 volumio volumio[1314]: info: Starting Shairport Sync Dec 22 00:03:04 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Dec 22 00:03:04 volumio volumio[1314]: info: Executing endpoint metavolumio Dec 22 00:03:04 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 22 00:03:04 volumio qobuz-connect[2687]: 20251222 00:03:04.969 [2687.2687] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 00:03:05 volumio sudo[2726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 00:03:05 volumio sudo[2726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:05 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 00:03:05 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 00:03:05 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:03:05 volumio systemd[1]: shairport-sync.service: Consumed 1.014s CPU time. Dec 22 00:03:05 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 00:03:05 volumio sudo[2726]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.694 [2687.2687] INFO VolumeManager: [0x1156368]: Setting new playback volume: 75 Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.694 [2687.2687] INFO VolumeManager: [0x1156368]: Setting new mute state: 0 Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.695 [2687.2687] INFO QobuzConnect: [0x1156d38]: Client initialized! Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.695 [2687.2687] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.743 [2687.2687] INFO LocalConfigManager: [0x1155c48]: Starting Local Configuration server Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.743 [2687.2687] INFO SampleApp: Starting Local configuration server Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.789 [2687.2687] INFO SampleApp: Playback volume changed: 75 Dec 22 00:03:05 volumio qobuz-connect[2687]: 20251222 00:03:05.789 [2687.2687] INFO SampleApp: Connected to UNIX socket client 0x1140818 Dec 22 00:03:07 volumio volumio[1314]: info: Asound.conf file unchanged, so no further update is needed Dec 22 00:03:07 volumio volumio[1314]: info: Output device has changed, restarting MPD Dec 22 00:03:08 volumio volumio[1314]: info: Output device has changed, restarting Shairport Sync Dec 22 00:03:08 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:08 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 00:03:09 volumio sudo[2762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 00:03:09 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:09 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:09 volumio sudo[2761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 00:03:09 volumio sudo[2761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:09 volumio mpd[2671]: 2025-12-22T00:03:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 00:03:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 00:03:09 volumio sudo[2579]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:09 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:09 volumio volumio[1314]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 00:03:09 volumio volumio[1314]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 00:03:09 volumio volumio[1314]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 00:03:09 volumio volumio[1314]: info: QobuzConnect: setDeactiveState invoked Dec 22 00:03:09 volumio volumio[1314]: info: CoreCommandRouter::volumioGetState Dec 22 00:03:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 00:03:10 volumio sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:03:10 volumio sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:10 volumio sudo[2781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 00:03:10 volumio sudo[2781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:11 volumio volumio[1314]: info: Preparing to generate the ALSA configuration file Dec 22 00:03:11 volumio sudo[2779]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:11 volumio sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 00:03:11 volumio sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:11 volumio sudo[2781]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:11 volumio sudo[2789]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:11 volumio sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 00:03:11 volumio sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 00:03:11 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 00:03:12 volumio qobuz-connect[2687]: 20251222 00:03:12.023 [2687.2687] INFO SampleApp: Stopping Local configuration server Dec 22 00:03:12 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 00:03:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 00:03:12 volumio systemd[1]: mpd.service: Consumed 3.760s CPU time. Dec 22 00:03:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 00:03:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 00:03:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 00:03:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 00:03:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 00:03:12 volumio sudo[2803]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 00:03:12 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 00:03:12 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:14 volumio qobuz-connect[2687]: 20251222 00:03:14.130 [2687.2687] INFO SampleApp: shat down connection on UNIX socket Dec 22 00:03:14 volumio volumio[1314]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 00:03:14 volumio volumio[1314]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 00:03:15 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 00:03:15 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:15 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 00:03:15 volumio sudo[2793]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:17 volumio volumio[1314]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Dec 22 00:03:17 volumio volumio[1314]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 00:03:17 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 00:03:17 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 00:03:17 volumio volumio[1314]: info: Reading ALSA contributions from plugins. Dec 22 00:03:17 volumio volumio[1314]: info: Shairport-Sync Started Dec 22 00:03:17 volumio volumio[1314]: info: TidalConnect service stoped! Dec 22 00:03:17 volumio volumio[1314]: info: TidalConnect service stoped! Dec 22 00:03:18 volumio volumio[1314]: error: Failed request for metavolumio API Dec 22 00:03:20 volumio volumio[1314]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 00:03:20 volumio volumio[1314]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 22 00:03:20 volumio volumio[1314]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 22 00:03:20 volumio volumio[1314]: at new NodeError (node:internal/errors:405:5) Dec 22 00:03:20 volumio volumio[1314]: at assert (node:internal/assert:14:11) Dec 22 00:03:20 volumio volumio[1314]: at internalConnectMultiple (node:net:1118:3) Dec 22 00:03:20 volumio volumio[1314]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 22 00:03:20 volumio volumio[1314]: at listOnTimeout (node:internal/timers:575:11) Dec 22 00:03:20 volumio volumio[1314]: at process.processTimers (node:internal/timers:514:7) { Dec 22 00:03:20 volumio volumio[1314]: code: 'ERR_INTERNAL_ASSERTION' Dec 22 00:03:20 volumio volumio[1314]: } Dec 22 00:03:20 volumio volumio[1314]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 00:03:20 volumio mpd[2805]: 2025-12-22T00:03:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 00:03:20 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 00:03:21 volumio sudo[2761]: pam_unix(sudo:session): session closed for user root Dec 22 00:03:28 volumio sudo[2893]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 00:02' Dec 22 00:03:28 volumio sudo[2893]: 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"