-- Logs begin at Mon 2026-01-26 19:16:36 EET, end at Mon 2026-01-26 19:18:27 EET. -- Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:00 volumio volumio[956]: info: Discovery: adding 7399905c-1911-4672-9e57-b1b7fffbf62c Jan 26 19:17:00 volumio volumio[956]: info: Discovery: Found device Volumio Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:00 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:00 volumio volumio[956]: info: Volumio called home Jan 26 19:17:00 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:00 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:00 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:00 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:00 volumio sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:00 volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:00 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:00 volumio sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:00 volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:00 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:00 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:17:00 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:17:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:00 volumio sudo[1520]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:00 volumio sudo[1518]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:00 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:17:00 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 26 19:17:00 volumio volumio[956]: Error adding Membership: Error: addMembership EINVAL Jan 26 19:17:00 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:17:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:00 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:00 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:00 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:01 volumio mpd[1493]: Jan 26 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:17:01 volumio systemd[1]: Started Music Player Daemon. Jan 26 19:17:01 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:01 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:01 volumio volumio[956]: info: Completed starting Core Plugins Jan 26 19:17:01 volumio volumio[956]: info: ------------------------------------------- Jan 26 19:17:01 volumio volumio[956]: info: ----- MyVolumio plugins startup ---- Jan 26 19:17:01 volumio volumio[956]: info: ------------------------------------------- Jan 26 19:17:01 volumio volumio[956]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 19:17:01 volumio volumio[956]: info: MPD running with PID1493 Jan 26 19:17:01 volumio volumio[956]: ,establishing connection Jan 26 19:17:01 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 26 19:17:01 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:01 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 26 19:17:01 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:01 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 26 19:17:01 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:01 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 26 19:17:01 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:01 volumio volumio[956]: error: updateQueue error: null Jan 26 19:17:01 volumio volumio[956]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 26 19:17:02 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 19:17:04 volumio ntpd[1251]: receive: Unexpected origin timestamp 0xed22200e.eac116ee does not match aorg 0000000000.00000000 from server@195.238.84.23 xmt 0xed222010.3333c1be Jan 26 19:17:04 volumio ntpd[1251]: receive: Unexpected origin timestamp 0xed22200e.eac551ff does not match aorg 0000000000.00000000 from server@195.238.84.84 xmt 0xed222010.22e707a4 Jan 26 19:17:04 volumio ntpd[1251]: receive: Unexpected origin timestamp 0xed22200e.eac449dd does not match aorg 0000000000.00000000 from server@213.91.190.205 xmt 0xed222010.1e049b28 Jan 26 19:17:04 volumio ntpd[1251]: receive: Unexpected origin timestamp 0xed22200e.eac227f5 does not match aorg 0000000000.00000000 from server@176.12.60.87 xmt 0xed222010.1e2148d0 Jan 26 19:17:04 volumio ntpd[1251]: receive: Unexpected origin timestamp 0xed22200e.eac660e6 does not match aorg 0000000000.00000000 from server@78.159.131.45 xmt 0xed222010.25bb1e52 Jan 26 19:17:04 volumio volumio[956]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 26 19:17:05 volumio sudo[1728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 19:17:05 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:05 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:05 volumio sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 19:17:05 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:05 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:05 volumio sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 19:17:05 volumio sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 19:17:05 volumio sudo[1735]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:05 volumio volumio[956]: info: Upmpdcli Daemon Started Jan 26 19:17:05 volumio sudo[1741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:17:05 volumio sudo[1741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:05 volumio sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:17:05 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:05 volumio systemd[1]: Started MPD Monitor Service. Jan 26 19:17:05 volumio sudo[1741]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:05 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:05 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:17:05 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:17:05 volumio mpd_monitor.sh[1758]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 19:17:05 volumio volumio[1738]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 26 19:17:05 volumio volumio[956]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 19:17:05 volumio volumio[956]: info: Received Get System Info Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 19:17:05 volumio volumio[956]: info: Discovery: Getting this device information Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: Listing playlists Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 19:17:05 volumio volumio[956]: info: Received Get System Info Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 19:17:05 volumio volumio[956]: info: Discovery: Getting this device information Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: Listing playlists Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 19:17:05 volumio volumio[956]: info: Received Get System Info Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 19:17:05 volumio volumio[956]: info: Discovery: Getting this device information Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:05 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:05 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 19:17:06 volumio volumio[1738]: ..............++++ Jan 26 19:17:07 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 19:17:08 volumio volumio[956]: info: Received Get System Info Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 19:17:08 volumio volumio[956]: info: Discovery: Getting this device information Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:08 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 19:17:08 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 26 19:17:09 volumio volumio[1738]: ........................................................................................................................................................++++ Jan 26 19:17:09 volumio volumio[1738]: e is 65537 (0x010001) Jan 26 19:17:09 volumio volumio[1738]: writing RSA key Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin bluetooth to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin multiroom to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin metavolumio to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin cd_controller to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 26 19:17:11 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 26 19:17:12 volumio volumio[956]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 26 19:17:12 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 26 19:17:12 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:12 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:12 volumio volumio[956]: info: Starting MyVolumio Remote Streaming Endpoints Jan 26 19:17:12 volumio volumio[956]: info: MyVolumio login type: Token Jan 26 19:17:12 volumio volumio[956]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 26 19:17:12 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 26 19:17:12 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 26 19:17:13 volumio volumio[956]: info: Starting Streaming Service Transparent Proxy Jan 26 19:17:13 volumio volumio[956]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 26 19:17:13 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 26 19:17:13 volumio volumio[956]: info: Streaming services startup Jan 26 19:17:13 volumio volumio[956]: info: Starting Streaming Daemon Jan 26 19:17:13 volumio sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 19:17:13 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:13 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:13 volumio volumio[956]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 26 19:17:13 volumio volumio[956]: error: Cannot start Volumio Streaming Daemon Jan 26 19:17:13 volumio volumio[956]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 19:17:13 volumio volumio[956]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 19:17:13 volumio volumio[956]: error: MyVolumio Custom Token format not valid, refreshing it Jan 26 19:17:13 volumio volumio[956]: STREAMING PROXY: Starting server on port 3245 Jan 26 19:17:13 volumio volumio[956]: Node JS runtime: 14 Jan 26 19:17:13 volumio volumio[956]: info: MyVolumio login type: Token Jan 26 19:17:14 volumio volumio[956]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 26 19:17:14 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 26 19:17:15 volumio volumio[956]: info: MyVolumio token set successfully Jan 26 19:17:15 volumio volumio[956]: info: MYVOLUMIO: Adding device Jan 26 19:17:15 volumio volumio[956]: info: MYVOLUMIO: Evaluating Server Jan 26 19:17:15 volumio volumio[956]: info: MyVolumio Plan changed: premium Jan 26 19:17:15 volumio volumio[956]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 26 19:17:15 volumio volumio[956]: info: Removing browser output: myVolumio user plan is not superstar Jan 26 19:17:15 volumio volumio[956]: info: Removing audio output: Jan 26 19:17:15 volumio volumio[956]: info: MYVOLUMIO: Adding device Jan 26 19:17:15 volumio volumio[956]: info: MYVOLUMIO: Evaluating Server Jan 26 19:17:15 volumio volumio[956]: info: Remote config written successfully Jan 26 19:17:15 volumio volumio[956]: info: Starting Tunnel 1 Jan 26 19:17:15 volumio volumio[956]: info: Starting Tunnel Connection Checker Jan 26 19:17:15 volumio volumio[956]: info: Completed starting MyVolumio Plugin Jan 26 19:17:15 volumio volumio[956]: info: MYVolumio Device enabled Jan 26 19:17:15 volumio volumio[956]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 26 19:17:15 volumio volumio[956]: info: MyVolumio status changed Jan 26 19:17:15 volumio volumio[956]: info: Streaming services startup Jan 26 19:17:15 volumio volumio[956]: info: Starting Streaming Daemon Jan 26 19:17:16 volumio sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 26 19:17:16 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:16 volumio volumio[956]: info: Setting Geolocation for MyVolumio to eu5 Jan 26 19:17:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:16 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:16 volumio volumio[956]: error: Cannot start Volumio Streaming Daemon Jan 26 19:17:16 volumio volumio[956]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 26 19:17:16 volumio volumio[956]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 26 19:17:16 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 26 19:17:16 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 26 19:17:16 volumio volumio[956]: info: [1769447836194] Starting BluetoothController Jan 26 19:17:16 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 26 19:17:17 volumio sudo[1849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 26 19:17:17 volumio sudo[1849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio sudo[1849]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio volumio[956]: info: MRS: MultiRoom plugin initialized Jan 26 19:17:17 volumio volumio[956]: info: MRS: STOPPING SNAPCLIENT Jan 26 19:17:17 volumio volumio[956]: info: MRS: Snap server stop Jan 26 19:17:17 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 26 19:17:17 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio volumio[956]: info: MRS: STOPPING volumioStreaming Jan 26 19:17:17 volumio sudo[1868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 26 19:17:17 volumio sudo[1868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 26 19:17:17 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 26 19:17:17 volumio sudo[1868]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio sudo[1874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 26 19:17:17 volumio sudo[1874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio sudo[1874]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 26 19:17:17 volumio volumio[956]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 26 19:17:17 volumio volumio[956]: info: Preparing to generate the ALSA configuration file Jan 26 19:17:17 volumio volumio[956]: info: Updating MyVolumio device info Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 26 19:17:17 volumio volumio[956]: info: Reading ALSA contributions from plugins. Jan 26 19:17:17 volumio volumio[956]: info: Setting Geolocation for MyVolumio to eu5 Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: Successfully Added MyVolumio device Jan 26 19:17:17 volumio volumio[956]: info: MRS: Removed streaming files Jan 26 19:17:17 volumio volumio[956]: info: MRS: volumioStreaming STOPPED Jan 26 19:17:17 volumio volumio[956]: info: MRS: SNAPSERVER STOPPED Jan 26 19:17:17 volumio volumio[956]: info: MRS: SNAPCLIENT STOPPED Jan 26 19:17:17 volumio volumio[956]: info: Asound.conf file written Jan 26 19:17:17 volumio sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 26 19:17:17 volumio sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio sudo[1877]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 26 19:17:17 volumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:17:17 volumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:17 volumio sudo[1882]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:17 volumio sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:17:17 volumio sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:17 volumio systemd[1]: Stopping Music Player Daemon... Jan 26 19:17:17 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:17:17 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:17:17 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 19:17:18 volumio systemd[1]: mpd.service: Succeeded. Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 26 19:17:18 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 19:17:18 volumio volumio[956]: info: MRS: Pushing multiroomSync output for this device Jan 26 19:17:18 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:18 volumio volumio[956]: info: Adding audio output: Jan 26 19:17:18 volumio volumio[956]: info: Adding audio output: Jan 26 19:17:18 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Jan 26 19:17:18 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: Starting Music Player Daemon... Jan 26 19:17:18 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 26 19:17:18 volumio volumio[956]: info: Adding METAVOLUMIO REST API Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 26 19:17:18 volumio volumio[956]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 26 19:17:18 volumio volumio[956]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 26 19:17:18 volumio volumio[956]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 26 19:17:18 volumio volumio[956]: info: Preparing CD Folders Jan 26 19:17:18 volumio volumio[956]: info: Adding CD REST API Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 26 19:17:18 volumio volumio[956]: info: Starting UDEV Watcher for CD Jan 26 19:17:18 volumio volumio[956]: info: Detecting CD presence with UDEV Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 26 19:17:18 volumio volumio[956]: info: Adding inputs REST Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 26 19:17:18 volumio volumio[956]: info: Scanning Audio Inputs Jan 26 19:17:18 volumio sudo[1894]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 19:17:18 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: Checking against Known Cards name Jan 26 19:17:18 volumio volumio[956]: info: Checking against Known Cards name Jan 26 19:17:18 volumio volumio[956]: info: Adding Server instance for streaming Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 26 19:17:18 volumio volumio[956]: error: Hi Res Audio Failed Login: Missing Login Data Jan 26 19:17:18 volumio volumio[956]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 19:17:18 volumio volumio[956]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 26 19:17:18 volumio volumio[956]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 26 19:17:18 volumio volumio[956]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 26 19:17:18 volumio sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:17:18 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 26 19:17:18 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 26 19:17:18 volumio volumio[956]: info: Adding TIDAL REST API Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 26 19:17:18 volumio volumio[956]: info: Stopping AccessToken refresher cron for QOBUZ Jan 26 19:17:18 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:17:18 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:17:18 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio volumio[956]: info: AccessToken refresher cron started for QOBUZ Jan 26 19:17:18 volumio volumio[956]: info: Adding QOBUZ REST API Endpoints Jan 26 19:17:18 volumio volumio[956]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 26 19:17:18 volumio volumio[956]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio' Jan 26 19:17:18 volumio volumio[956]: Can't get device info: No such device Jan 26 19:17:18 volumio volumio[956]: info: MPD Permissions set Jan 26 19:17:18 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:18 volumio volumio[956]: info: Preparing to generate the ALSA configuration file Jan 26 19:17:18 volumio volumio[956]: info: Successfully Updated MyVolumio device Jan 26 19:17:18 volumio sudo[1924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:18 volumio sudo[1924]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio volumio[956]: info: Asound.conf file written Jan 26 19:17:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:17:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:17:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:18 volumio sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 26 19:17:18 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:18 volumio sudo[1928]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio sudo[1924]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 26 19:17:18 volumio sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:17:18 volumio sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio sudo[1936]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:17:18 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 26 19:17:18 volumio systemd[1]: mpd.service: Succeeded. Jan 26 19:17:18 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 19:17:18 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:17:18 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:18 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:18 volumio volumio[956]: info: Preparing to generate the ALSA configuration file Jan 26 19:17:18 volumio systemd[1]: Starting Music Player Daemon... Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: MRS: Found cast device: SDSTB01-31cbbb3fda8dcf22ff5ae5c014e657a9 Jan 26 19:17:18 volumio volumio[956]: info: Adding audio output: Jan 26 19:17:18 volumio volumio[956]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 26 19:17:18 volumio volumio[956]: info: Reading ALSA contributions from plugins. Jan 26 19:17:18 volumio sudo[1944]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 19:17:18 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:17:18 volumio sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: MPD Permissions set Jan 26 19:17:18 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:18 volumio volumio[956]: info: Executing endpoint qc_getconfig Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 19:17:18 volumio sudo[1951]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio qobuz-connect[1915]: 20260126 19:17:18.670 [1915.1915] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio sudo[1957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:17:18 volumio sudo[1957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 26 19:17:18 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jan 26 19:17:18 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 26 19:17:18 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:17:18 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:17:18 volumio sudo[1957]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:18 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:18 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:18 volumio volumio[956]: info: Executing endpoint qc_getconfig Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 19:17:18 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:18 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:17:18 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.788 [1963.1963] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 19:17:18 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 19:17:18 volumio volumio[956]: info: Updating MyVolumio device info Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.820 [1963.1963] INFO VolumeManager: [0x560cac4d0380]: Setting new playback volume: 75 Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.820 [1963.1963] INFO VolumeManager: [0x560cac4d0380]: Setting new mute state: 0 Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.820 [1963.1963] INFO QobuzConnect: [0x560cac4d15a0]: Client initialized! Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.820 [1963.1963] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 26 19:17:18 volumio volumio[956]: info: Asound.conf file written Jan 26 19:17:18 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.832 [1963.1963] INFO LocalConfigManager: [0x560cac4cf670]: Starting Local Configuration server Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.832 [1963.1963] INFO SampleApp: Starting Local configuration server Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.833 [1963.1963] INFO SampleApp: Connected to UNIX socket client 0x560cac4b71c0 Jan 26 19:17:18 volumio sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 26 19:17:18 volumio sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio sudo[1976]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 26 19:17:18 volumio sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:17:18 volumio sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:18 volumio sudo[1982]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:18 volumio qobuz-connect[1963]: 20260126 19:17:18.938 [1963.1963] INFO SampleApp: Playback volume changed: 75 Jan 26 19:17:18 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:17:18 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:18 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 26 19:17:18 volumio systemd[1]: mpd.service: Succeeded. Jan 26 19:17:18 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 19:17:18 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:17:18 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:18 volumio systemd[1]: Starting Music Player Daemon... Jan 26 19:17:18 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:17:18 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:18 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:19 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:19 volumio volumio[956]: info: Successfully Added MyVolumio device Jan 26 19:17:19 volumio volumio[956]: info: MPD Permissions set Jan 26 19:17:19 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:19 volumio sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:19 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:19 volumio sudo[1990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 19:17:19 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:19 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:17:19 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:17:19 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:19 volumio qobuz-connect[1963]: 20260126 19:17:19.090 [1963.1963] INFO SampleApp: Stopping Local configuration server Jan 26 19:17:19 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 26 19:17:19 volumio volumio[956]: info: Successfully Updated MyVolumio device Jan 26 19:17:19 volumio qobuz-connect[1963]: 20260126 19:17:19.100 [1963.1963] INFO SampleApp: shat down connection on UNIX socket Jan 26 19:17:19 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:17:19 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 26 19:17:19 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 26 19:17:19 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:17:19 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio volumio[956]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 26 19:17:19 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:19 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:19 volumio sudo[2011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:17:19 volumio sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:19 volumio volumio[956]: info: Executing endpoint qc_getconfig Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 19:17:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:17:19 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:17:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.170 [2009.2009] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.173 [2009.2009] INFO VolumeManager: [0x562738cef380]: Setting new playback volume: 75 Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.173 [2009.2009] INFO VolumeManager: [0x562738cef380]: Setting new mute state: 0 Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.173 [2009.2009] INFO QobuzConnect: [0x562738cf05a0]: Client initialized! Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.173 [2009.2009] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 26 19:17:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:17:19 volumio volumio[956]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 19:17:19 volumio volumio[956]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 19:17:19 volumio sudo[2011]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.190 [2009.2009] INFO LocalConfigManager: [0x562738cee670]: Starting Local Configuration server Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.190 [2009.2009] INFO SampleApp: Starting Local configuration server Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.191 [2009.2009] INFO SampleApp: Connected to UNIX socket client 0x562738cd61c0 Jan 26 19:17:19 volumio qobuz-connect[2009]: 20260126 19:17:19.320 [2009.2009] INFO SampleApp: Playback volume changed: 75 Jan 26 19:17:19 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:19 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:19 volumio mpd[2003]: Jan 26 19:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:17:19 volumio systemd[1]: Started Music Player Daemon. Jan 26 19:17:19 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio sudo[1884]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:19 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 26 19:17:19 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:19 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 26 19:17:19 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:19 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 26 19:17:19 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:19 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 26 19:17:19 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:17:19 volumio volumio[956]: error: updateQueue error: null Jan 26 19:17:20 volumio sudo[2027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 26 19:17:20 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:20 volumio systemd[1]: Started MyVolumio SSH Tunnel. Jan 26 19:17:20 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:20 volumio volumio[956]: info: Remote SSH Started Jan 26 19:17:20 volumio autossh[2030]: port set to 0, monitoring disabled Jan 26 19:17:20 volumio autossh[2030]: starting ssh (count 1) Jan 26 19:17:20 volumio autossh[2030]: ssh child pid is 2033 Jan 26 19:17:20 volumio volumio[956]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 26 19:17:20 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:20 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:20 volumio volumiossh-tunnel[2029]: Warning: Permanently added '[eu5.myvolumio.org]:2222,[185.14.184.111]:2222' (RSA) to the list of known hosts. Jan 26 19:17:21 volumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 26 19:17:21 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:21 volumio systemd[1]: Started Volumio Bluetooth Module. Jan 26 19:17:21 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:21 volumio volumio[956]: info: Volumio BT Module successfully started Jan 26 19:17:21 volumio sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 26 19:17:21 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:21 volumio volumiobt[2037]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 26 19:17:21 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:21 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:17:21 volumio volumio[956]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:17:21 volumio volumio[956]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:17:21 volumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 19:17:21 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:21 volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 26 19:17:21 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:21 volumio volumio[956]: info: Executing endpoint tc_getconfig Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 26 19:17:21 volumio vtcs[2052]: STARTING TidalConnect services, version: 1.5.2.56 Jan 26 19:17:21 volumio vtcs[2052]: STARTED TidalConnect services. Jan 26 19:17:21 volumio volumio[956]: info: Executing endpoint tc_connect Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 26 19:17:21 volumio volumio[956]: info: Connecting to TidalConnect Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:21 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:21 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:21 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:22 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 26 19:17:22 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:22 volumio volumiobt[2037]: pulseaudio: no process found Jan 26 19:17:22 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 19:17:22 volumio volumio[956]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 26 19:17:22 volumio volumio[956]: info: Received Get System Version Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:17:22 volumio volumio[956]: info: Received Get System Info Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 26 19:17:22 volumio volumio[956]: info: Discovery: Getting this device information Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 26 19:17:22 volumio dbus-daemon[674]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.26' (uid=1000 pid=2075 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 26 19:17:22 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:17:22 volumio dbus-daemon[674]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 26 19:17:22 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Successfully called chroot. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Successfully dropped privileges. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Successfully limited resources. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Running. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Canary thread running. Jan 26 19:17:22 volumio rtkit-daemon[2076]: Watchdog thread running. Jan 26 19:17:22 volumio dbus-daemon[674]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.27' (uid=0 pid=2076 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 26 19:17:22 volumio systemd[1]: Starting Authorization Manager... Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:17:22 volumio polkitd[2079]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 26 19:17:22 volumio dbus-daemon[674]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 26 19:17:22 volumio systemd[1]: Started Authorization Manager. Jan 26 19:17:22 volumio pulseaudio[2075]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 26 19:17:22 volumio pulseaudio[2075]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 26 19:17:22 volumio volumiobt[2037]: Applying permissions Jan 26 19:17:22 volumio volumiobt[2037]: Setting BT discoverable and pairable Jan 26 19:17:22 volumio pulseaudio[2075]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 26 19:17:22 volumio volumiobt[2037]: [79B blob data] Jan 26 19:17:22 volumio volumiobt[2037]: Invalid command in menu main: -e Jan 26 19:17:22 volumio volumiobt[2037]: Jan 26 19:17:22 volumio volumiobt[2037]: Use "help" for a list of available commands in a menu. Jan 26 19:17:22 volumio volumiobt[2037]: Use "menu " if you want to enter any submenu. Jan 26 19:17:22 volumio volumiobt[2037]: Use "back" if you want to return to menu main. Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> agent on Jan 26 19:17:22 volumio volumiobt[2037]: Failed to register agent object Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> discoverable on Jan 26 19:17:22 volumio volumiobt[2037]: No default controller available Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> pairable on Jan 26 19:17:22 volumio volumiobt[2037]: No default controller available Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> agent NoInputNoOutput Jan 26 19:17:22 volumio volumiobt[2037]: Failed to register agent object Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> default-agent Jan 26 19:17:22 volumio volumiobt[2037]: No agent is registered Jan 26 19:17:22 volumio volumiobt[2037]: [bluetoothctl]> quit Jan 26 19:17:22 volumio volumiobt[2037]: [65B blob data] Jan 26 19:17:22 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:17:22 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:22 volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 19:17:22 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 19:17:22 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 19:17:22 volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 19:17:22 volumio sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:17:22 volumio sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:17:22 volumio sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:17:22 volumio systemd[1]: Started MPD Monitor Service. Jan 26 19:17:22 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:17:22 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:17:22 volumio mpd_monitor.sh[2093]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 19:17:22 volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 19:17:22 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 19:17:22 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 19:17:22 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:17:22 volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 19:17:22 volumio systemd[1]: Started MPD Monitor Service. Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:17:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:17:22 volumio sudo[2091]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:22 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root Jan 26 19:17:22 volumio mpd_monitor.sh[2097]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 19:17:22 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:17:23 volumio volumio[956]: info: MRS: Getting audio outputs on start Jan 26 19:17:23 volumio volumio[956]: info: MRS: Requesting all other devices output Jan 26 19:17:23 volumio volumio[956]: info: Executing endpoint metavolumio Jan 26 19:17:23 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 19:17:24 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 26 19:17:24 volumio volumio[956]: info: TidalConnect service started! Jan 26 19:17:24 volumio volumio[956]: [Metrics] CommandRouter: 35s 74.11ms Jan 26 19:17:24 volumio volumio[956]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 19:17:24 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:24 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:24 volumio volumio[956]: info: CoreCommandRouter::Close All Modals sent Jan 26 19:17:24 volumio volumio[956]: info: CoreCommandRouter::Close All Modals sent Jan 26 19:17:24 volumio volumio[956]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 26 19:17:24 volumio volumio[956]: No protocol specified Jan 26 19:17:24 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:17:24 volumio volumio[956]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jan 26 19:17:24 volumio volumio[956]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Jan 26 19:17:24 volumio volumio[956]: aplay: main:828: audio open error: No such device Jan 26 19:17:25 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 19:17:25 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:17:25 volumio volumio[956]: No protocol specified Jan 26 19:17:25 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:17:25 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 19:17:27 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 26 19:17:27 volumio volumio[956]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 26 19:17:27 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Currently active: radio_paradise Jan 26 19:17:28 volumio volumio[956]: info: Stopping currently active service Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioStop Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::stop Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 19:17:28 volumio volumio[956]: info: TidalConnect Active Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Currently active: radio_paradise Jan 26 19:17:28 volumio volumio[956]: info: Setting Volatile state to tidalconnect Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:28 volumio volumio[956]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7itHw1ULbIXf9KtYhn6q8JalYl63.22f5d9bb954e81400c278c40684259f9.state.status' Jan 26 19:17:28 volumio vtcs[2052]: [2026-01-26 19:17:28.791] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Jan 26 19:17:28 volumio volumio[956]: info: Pushing metadata Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:17:28 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:28 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:28 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:28 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:28 volumio volumio[956]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7itHw1ULbIXf9KtYhn6q8JalYl63.22f5d9bb954e81400c278c40684259f9.state.status' Jan 26 19:17:28 volumio volumio[956]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.7itHw1ULbIXf9KtYhn6q8JalYl63.22f5d9bb954e81400c278c40684259f9.state.status' Jan 26 19:17:29 volumio volumio[956]: info: Pushing metadata Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:29 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:29 volumio vtcs[2052]: [close:85] Entering Jan 26 19:17:29 volumio vtcs[2052]: [close:100] Exiting Jan 26 19:17:29 volumio vtcs[2052]: [open:50] Opening with sample rate: 96000, type: 3 Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:29 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:29 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:29 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:29 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:29 volumio vtcs[2052]: [2026-01-26 19:17:29.520] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Jan 26 19:17:30 volumio volumio[956]: info: Executing endpoint metavolumio Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 19:17:30 volumio volumio[956]: info: Executing endpoint metavolumio Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 19:17:30 volumio volumio[956]: info: Executing endpoint metavolumio Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:30 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 26 19:17:31 volumio volumio-remote-updater[669]: No test mode Jan 26 19:17:31 volumio volumio-remote-updater[669]: No alpha test mode Jan 26 19:17:31 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 26 19:17:31 volumio volumio[956]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 26 19:17:31 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 26 19:17:31 volumio volumio[956]: info: BOOT COMPLETED Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 19:17:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:17:36 volumio vtcs[2052]: [start:106] Entering Jan 26 19:17:36 volumio vtcs[2052]: [start:113] Reopening PCM device... Jan 26 19:17:36 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:17:36 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:17:36 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:17:36 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:17:36 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:17:36 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:17:36 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:17:36 volumio vtcs[2052]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jan 26 19:17:36 volumio vtcs[2052]: ALSA lib /home/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Jan 26 19:17:36 volumio vtcs[2052]: [reopen:182] ALSA error: No such device Jan 26 19:17:36 volumio vtcs[2052]: [start:114] Error: 3 Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 19:17:51 volumio volumio[956]: No protocol specified Jan 26 19:17:51 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:17:51 volumio volumio[956]: No protocol specified Jan 26 19:17:51 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 19:17:51 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 19:18:21 volumio volumio[956]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 26 19:18:21 volumio volumio[956]: info: Preparing to save Alsa Options, stopping services first Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioPause Jan 26 19:18:21 volumio volumio[956]: info: CoreStateMachine::pause Jan 26 19:18:21 volumio volumio[956]: info: CoreStateMachine::stPlaybackTimer Jan 26 19:18:21 volumio volumio[956]: info: CoreStateMachine::servicePause Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::servicePause Jan 26 19:18:21 volumio volumio[956]: info: Received pause Jan 26 19:18:21 volumio volumio[956]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"DENAFRIPS USB Audio V3.14"}} Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:18:21 volumio vtcs[2052]: [pause:147] Entering Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: Setting mixer PCM for card DENAFRIPS USB Audio V3.14 Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:21 volumio volumio[956]: info: Relinquishing Volumio State Jan 26 19:18:21 volumio volumio[956]: verbose: UNSET VOLATILE: Service: tidalconnect Jan 26 19:18:21 volumio volumio[956]: info: unSetVolatile called Jan 26 19:18:21 volumio volumio[956]: info: Relinquishing Volumio State to another service Jan 26 19:18:21 volumio vtcs[2052]: [2026-01-26 19:18:21.427] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 26 19:18:21 volumio vtcs[2052]: [close:85] Entering Jan 26 19:18:21 volumio vtcs[2052]: [close:100] Exiting Jan 26 19:18:21 volumio vtcs[2052]: [2026-01-26 19:18:21.429] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=8 errorno=104 error=Connection reset by peer Jan 26 19:18:21 volumio vtcs[2052]: [2026-01-26 19:18:21.429] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 26 19:18:21 volumio sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:21 volumio sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio vtcs[2052]: [2026-01-26 19:18:21.462] [tisoc] [warning] [TidalEvents.cpp:419] Continue event sending result: 20 Jan 26 19:18:21 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Jan 26 19:18:21 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 26 19:18:21 volumio systemd[1]: vtcs.service: Succeeded. Jan 26 19:18:21 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Jan 26 19:18:21 volumio sudo[2266]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 26 19:18:21 volumio volumio[956]: info: Updating Volume Controller Parameters: Device: 5 Name: DENAFRIPS USB Audio V3.14 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 26 19:18:21 volumio volumio[956]: info: Disabling external Volume Control Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 19:18:21 volumio volumio[956]: info: Preparing to generate the ALSA configuration file Jan 26 19:18:21 volumio sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:21 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:21 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:21 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:18:21 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio volumio[956]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 26 19:18:21 volumio volumio[956]: info: Reading ALSA contributions from plugins. Jan 26 19:18:21 volumio volumio[956]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:18:21 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 26 19:18:21 volumio qobuz-connect[2009]: 20260126 19:18:21.634 [2009.2009] INFO SampleApp: Stopping Local configuration server Jan 26 19:18:21 volumio qobuz-connect[2009]: 20260126 19:18:21.644 [2009.2009] INFO SampleApp: shat down connection on UNIX socket Jan 26 19:18:21 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 26 19:18:21 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 26 19:18:21 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:18:21 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 19:18:21 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:21 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:21 volumio volumio[956]: info: VolumeController:: Volume=undefined Mute =false Jan 26 19:18:21 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:18:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:18:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:18:21 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:21 volumio volumio[956]: info: Executing endpoint qc_getconfig Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.806 [2287.2287] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.809 [2287.2287] INFO VolumeManager: [0x5651bf3d4380]: Setting new playback volume: 75 Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.809 [2287.2287] INFO VolumeManager: [0x5651bf3d4380]: Setting new mute state: 0 Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.809 [2287.2287] INFO QobuzConnect: [0x5651bf3d55a0]: Client initialized! Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.809 [2287.2287] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 26 19:18:21 volumio volumio[956]: info: Asound.conf file written Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.819 [2287.2287] INFO LocalConfigManager: [0x5651bf3d3670]: Starting Local Configuration server Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.819 [2287.2287] INFO SampleApp: Starting Local configuration server Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.820 [2287.2287] INFO SampleApp: Connected to UNIX socket client 0x5651bf3bb1c0 Jan 26 19:18:21 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 26 19:18:21 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 26 19:18:21 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:18:21 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:21 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:21 volumio sudo[2303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:18:21 volumio sudo[2303]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:21 volumio systemd[1]: Stopping Music Player Daemon... Jan 26 19:18:21 volumio qobuz-connect[2287]: 20260126 19:18:21.956 [2287.2287] INFO SampleApp: Playback volume changed: 75 Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:18:21 volumio volumio[956]: No protocol specified Jan 26 19:18:21 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:21 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:21 volumio systemd[1]: mpd.service: Succeeded. Jan 26 19:18:21 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 19:18:21 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:18:21 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:21 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:21 volumio volumio[956]: info: Relinquishing Volumio State Jan 26 19:18:21 volumio systemd[1]: Starting Music Player Daemon... Jan 26 19:18:22 volumio volumio[956]: info: Output device has changed, restarting MPD Jan 26 19:18:22 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:22 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: info: Output device has changed, restarting Shairport Sync Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:22 volumio sudo[2308]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 19:18:22 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:18:22 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:18:22 volumio sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 26 19:18:22 volumio systemd[1]: mpd.service: Succeeded. Jan 26 19:18:22 volumio sudo[2312]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio systemd[1]: Stopped Music Player Daemon. Jan 26 19:18:22 volumio systemd[1]: Starting Music Player Daemon... Jan 26 19:18:22 volumio volumio[956]: No protocol specified Jan 26 19:18:22 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:22 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:18:22 volumio volumio[956]: No protocol specified Jan 26 19:18:22 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:22 volumio volumio[956]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio volumio[956]: info: Relinquishing Volumio State Jan 26 19:18:22 volumio sudo[2325]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 26 19:18:22 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:22 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio sudo[2335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:22 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2335]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 26 19:18:22 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:22 volumio sudo[2335]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:18:22 volumio sudo[2346]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:22 volumio sudo[2346]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio sudo[2352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:18:22 volumio sudo[2352]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 26 19:18:22 volumio sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio qobuz-connect[2287]: 20260126 19:18:22.369 [2287.2287] INFO SampleApp: Stopping Local configuration server Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio sudo[2354]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio qobuz-connect[2287]: 20260126 19:18:22.379 [2287.2287] INFO SampleApp: shat down connection on UNIX socket Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 26 19:18:22 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio sudo[2357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 26 19:18:22 volumio sudo[2357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:18:22 volumio sudo[2352]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio volumio[956]: info: VolumeController::SetAlsaVolume0 Jan 26 19:18:22 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Jan 26 19:18:22 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jan 26 19:18:22 volumio systemd[1]: qobuz-connect.service: Succeeded. Jan 26 19:18:22 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Jan 26 19:18:22 volumio systemd[1]: Started Volumio Qobuz Connect Service. Jan 26 19:18:22 volumio sudo[2357]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:18:22 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:18:22 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio volumio[956]: info: MPD Permissions set Jan 26 19:18:22 volumio volumio[956]: info: MPD Permissions set Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio volumio[956]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 26 19:18:22 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:22 volumio volumio[956]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 26 19:18:22 volumio volumio[956]: info: QobuzConnect: setDeactiveState invoked Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio volumio[956]: info: Executing endpoint qc_getconfig Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.565 [2360.2360] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.568 [2360.2360] INFO VolumeManager: [0x5577f3741380]: Setting new playback volume: 75 Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.568 [2360.2360] INFO VolumeManager: [0x5577f3741380]: Setting new mute state: 0 Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.568 [2360.2360] INFO QobuzConnect: [0x5577f37425a0]: Client initialized! Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.572 [2360.2360] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.589 [2360.2360] INFO LocalConfigManager: [0x5577f3740670]: Starting Local Configuration server Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.589 [2360.2360] INFO SampleApp: Starting Local configuration server Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.590 [2360.2360] INFO SampleApp: Connected to UNIX socket client 0x5577f37281c0 Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:22 volumio volumio[956]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 26 19:18:22 volumio volumio[956]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 26 19:18:22 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:18:22 volumio volumio[956]: info: Starting Shairport Sync Jan 26 19:18:22 volumio qobuz-connect[2360]: 20260126 19:18:22.714 [2360.2360] INFO SampleApp: Playback volume changed: 75 Jan 26 19:18:22 volumio sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:18:22 volumio sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:22 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:18:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:18:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio sudo[2382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:18:22 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:18:22 volumio sudo[2379]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:18:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:18:22 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 26 19:18:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:18:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:22 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:18:22 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:22 volumio volumio[956]: info: Shairport-Sync Started Jan 26 19:18:23 volumio mpd[2333]: Jan 26 19:18 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:18:23 volumio systemd[1]: Started Music Player Daemon. Jan 26 19:18:23 volumio sudo[2303]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:23 volumio sudo[2317]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:23 volumio volumio[956]: error: MPD error: The expression evaluated to a falsy value: Jan 26 19:18:23 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:18:23 volumio volumio[956]: error: The expression evaluated to a falsy value: Jan 26 19:18:23 volumio volumio[956]: assert.ok(self.idling) Jan 26 19:18:23 volumio volumio[956]: error: updateQueue error: null Jan 26 19:18:24 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:24 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:24 volumio volumio[956]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:24 volumio volumio[956]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:24 volumio sudo[2402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 19:18:24 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:24 volumio systemd[1]: Started Volumio Tidal Connect Service. Jan 26 19:18:24 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:24 volumio volumio[956]: info: Executing endpoint tc_getconfig Jan 26 19:18:24 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 26 19:18:24 volumio vtcs[2404]: STARTING TidalConnect services, version: 1.5.2.56 Jan 26 19:18:24 volumio vtcs[2404]: STARTED TidalConnect services. Jan 26 19:18:24 volumio volumio[956]: info: Executing endpoint tc_connect Jan 26 19:18:24 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 26 19:18:24 volumio volumio[956]: info: Connecting to TidalConnect Jan 26 19:18:24 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:18:24 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:24 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:18:24 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:18:24 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:24 volumio volumio[956]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Jan 26 19:18:25 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:25 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:25 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:25 volumio volumio[956]: info: TidalConnect service stoped! Jan 26 19:18:25 volumio volumio[956]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:25 volumio volumio[956]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:25 volumio volumio[956]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:25 volumio volumio[956]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 26 19:18:25 volumio sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 19:18:25 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:25 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:25 volumio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 26 19:18:25 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:25 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 26 19:18:25 volumio volumio[956]: info: CoreStateMachine::getcurrentVolume Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 19:18:25 volumio volumio[956]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 26 19:18:25 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:25 volumio volumio[956]: info: VolumeController:: Volume=undefined Mute =false Jan 26 19:18:25 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:18:25 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:18:25 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:25 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 19:18:25 volumio volumio[956]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: No protocol specified Jan 26 19:18:25 volumio volumio[956]: xcb_connection_has_error() returned true Jan 26 19:18:25 volumio volumio[956]: info: VolumeController:: Volume=undefined Mute =false Jan 26 19:18:25 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:18:25 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:18:25 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:18:25 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:25 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:26 volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:18:26 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:26 volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 19:18:26 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 19:18:26 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 19:18:26 volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 19:18:26 volumio sudo[2469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 26 19:18:26 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:18:26 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:26 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:26 volumio systemd[1]: Started MPD Monitor Service. Jan 26 19:18:26 volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:26 volumio mpd_monitor.sh[2470]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 19:18:26 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:18:26 volumio systemd[1]: Stopping MPD Monitor Service... Jan 26 19:18:26 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 26 19:18:26 volumio systemd[1]: mpd_monitor.service: Succeeded. Jan 26 19:18:26 volumio systemd[1]: Stopped MPD Monitor Service. Jan 26 19:18:26 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 26 19:18:26 volumio volumio[956]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 26 19:18:26 volumio systemd[1]: Started MPD Monitor Service. Jan 26 19:18:26 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root Jan 26 19:18:26 volumio mpd_monitor.sh[2473]: MPD Monitor Service: Starting MPD Monitor Service Jan 26 19:18:26 volumio volumio[956]: info: Successfully started MPD Monitor Jan 26 19:18:26 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::volumioPlay Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::play index undefined Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 26 19:18:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::startPlaybackTimer Jan 26 19:18:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 26 19:18:27 volumio volumio[956]: verbose: ControllerMpd::sendMpdCommand play Jan 26 19:18:27 volumio volumio[956]: info: sendMpdCommand play took 3 milliseconds Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::servicePushState Jan 26 19:18:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:27 volumio volumio[956]: verbose: STATE SERVICE {"status":"play"} Jan 26 19:18:27 volumio volumio[956]: verbose: CURRENT POSITION 0 Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::syncState stateService play Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::syncState currentStatus pause Jan 26 19:18:27 volumio volumio[956]: info: CoreStateMachine::pushState Jan 26 19:18:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Jan 26 19:18:27 volumio volumio[956]: info: MRS: Pushing multiroomSync output update for this device Jan 26 19:18:27 volumio volumio[956]: info: MRS: Pushing multiroomSync output Jan 26 19:18:27 volumio volumio[956]: info: CoreCommandRouter::volumioGetState Jan 26 19:18:27 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Jan 26 19:18:27 volumio volumio[956]: info: [1769447907227] [RadioParadise] getMetadata started with url undefined Jan 26 19:18:27 volumio volumio[956]: info: [1769447907253] [RadioParadise] Error: connect ECONNREFUSED 127.0.0.1:443 Jan 26 19:18:27 volumio volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 19:18:27 volumio volumio[956]: TypeError: Cannot read property 'toUpperCase' of undefined Jan 26 19:18:27 volumio volumio[956]: at ControllerRadioParadise.errorToast (/data/plugins/music_service/radio_paradise/index.js:481:41) Jan 26 19:18:27 volumio volumio[956]: at ClientRequest. (/data/plugins/music_service/radio_paradise/index.js:452:14) Jan 26 19:18:27 volumio volumio[956]: at ClientRequest.emit (events.js:400:28) Jan 26 19:18:27 volumio volumio[956]: at TLSSocket.socketErrorListener (_http_client.js:475:9) Jan 26 19:18:27 volumio volumio[956]: at TLSSocket.emit (events.js:400:28) Jan 26 19:18:27 volumio volumio[956]: at emitErrorNT (internal/streams/destroy.js:106:8) Jan 26 19:18:27 volumio volumio[956]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Jan 26 19:18:27 volumio volumio[956]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jan 26 19:18:27 volumio volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 19:18:27 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-26 19:17 Jan 26 19:18:27 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"