-- Logs begin at Thu 2025-06-12 08:42:28 +07, end at Thu 2025-06-12 08:44:41 +07. -- Jun 12 08:43:00 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 12 08:43:00 volumio volumio[1204]: info: Refreshing Cached IP Addresses Jun 12 08:43:01 volumio dbus-daemon[954]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.6' (uid=0 pid=1591 comm="timedatectl set-time 2025-06-12 08:42:53 ") Jun 12 08:43:02 volumio volumio[1204]: info: Loading plugin "services"... Jun 12 08:43:02 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 08:43:02 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 12 08:43:02 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:02 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:02 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 08:43:02 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:02 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:02 volumio volumio[1204]: info: Loading plugin "alsa_controller"... Jun 12 08:43:04 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:04 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "wizard"... Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "networkfs"... Jun 12 08:43:06 volumio systemd[1]: Starting Time & Date Service... Jun 12 08:43:06 volumio dbus-daemon[954]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 12 08:43:06 volumio systemd[1]: Started Time & Date Service. Jun 12 08:43:01 volumio dbus-daemon[954]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 12 08:43:01 volumio systemd[1]: Started Hostname Service. Jun 12 08:43:01 volumio volumio[1204]: info: Loading plugin "ytmusic"... Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "outputs"... Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "albumart"... Jun 12 08:43:06 volumio volumio[1204]: info: Plugin example_plugin is not enabled Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "inputs"... Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "updater_comm"... Jun 12 08:43:06 volumio volumio[1204]: Forking 7 albumart workers Jun 12 08:43:06 volumio volumio[1204]: info: Plugin mpdemulation is not enabled Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "rest_api"... Jun 12 08:43:06 volumio volumio[1204]: info: Loading plugin "websocket"... Jun 12 08:43:06 volumio volumio[1204]: info: Starting Socket.io Server version 2.3.0 Jun 12 08:43:06 volumio volumio[1204]: info: Loading i18n strings for locale en Jun 12 08:43:06 volumio volumio[1204]: Updating browse sources language Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: Starting albumart workers Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::initPlayerControls Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: Express server listening on port 3000 Jun 12 08:43:06 volumio volumio[1204]: [Metrics] WebUI: 32s 429.04ms Jun 12 08:43:06 volumio volumio[1204]: info: Setting Device type: x86 Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::resetVolumioState Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::getcurrentVolume Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio sudo[1913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 12 08:43:06 volumio sudo[1913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio sudo[1913]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 12 08:43:06 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 12 08:43:06 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio volumio[1204]: info: Completed loading Core Plugins Jun 12 08:43:06 volumio volumio[1204]: info: Preparing to generate the ALSA configuration file Jun 12 08:43:06 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: Cannot read play queue from file Jun 12 08:43:06 volumio volumio[1204]: info: Volumio Network Manager: Network status updated: 1 Jun 12 08:43:06 volumio volumio[1204]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 12 08:43:06 volumio volumio[1204]: wlan0 Interface doesn't support scanning. Jun 12 08:43:06 volumio volumio[1204]: info: Cannot use regular scanning, forcing with ap-force Jun 12 08:43:06 volumio sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 12 08:43:06 volumio sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio sudo[1949]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: command failed: No such device (-19) Jun 12 08:43:06 volumio volumio[1204]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 12 08:43:06 volumio volumio[1204]: command failed: No such device (-19) Jun 12 08:43:06 volumio volumio[1204]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 12 08:43:06 volumio volumio[1204]: info: VolumeController:: Volume=41 Mute =false Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::pushState Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::updateTrackBlock Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrackBlock Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 08:43:06 volumio volumio[1204]: info: Asound.conf file unchanged, so no further update is needed Jun 12 08:43:06 volumio volumio[1204]: info: Output device has changed, restarting MPD Jun 12 08:43:06 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 08:43:06 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio volumio[1204]: info: Output device has changed, restarting Shairport Sync Jun 12 08:43:06 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 08:43:06 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio systemd[1]: Stopping Music Player Daemon... Jun 12 08:43:06 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 08:43:06 volumio volumio[1204]: info: ___________ START PLUGINS ___________ Jun 12 08:43:06 volumio volumio[1204]: info: ControllerMpd::onStart: Initializing MPD Jun 12 08:43:06 volumio volumio[1204]: info: Creating MPD Configuration file Jun 12 08:43:06 volumio sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 08:43:06 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 08:43:06 volumio volumio[1204]: info: [1749692586736] CoreMusicLibrary::Adding element Media Servers Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:43:06 volumio sudo[1980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 08:43:06 volumio sudo[1980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 08:43:06 volumio volumio[1204]: info: [1749692586765] CoreMusicLibrary::Adding element Last_100 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 08:43:06 volumio volumio[1204]: info: [1749692586765] CoreMusicLibrary::Adding element Webradio Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:43:06 volumio volumio[1204]: info: Initializing BBC Radios Jun 12 08:43:06 volumio systemd[1]: mpd.service: Succeeded. Jun 12 08:43:06 volumio systemd[1]: Stopped Music Player Daemon. Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: Creating Spotify config file Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 12 08:43:06 volumio volumio[1204]: info: [1749692586793] CoreMusicLibrary::Adding element YouTube Music Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:43:06 volumio volumio[1204]: Cannot find translation for source YouTube Music Jun 12 08:43:06 volumio volumio[1204]: info: Volumio Calling Home Jun 12 08:43:06 volumio systemd[1]: Starting Music Player Daemon... Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::setRepeat null single undefined Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::pushState Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::setRandom null Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::pushState Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Jun 12 08:43:06 volumio sudo[1991]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 08:43:06 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio volumio[1204]: info: MPD Permissions set Jun 12 08:43:06 volumio volumio[1204]: info: MPD Permissions set Jun 12 08:43:06 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: Spotify config file written Jun 12 08:43:06 volumio sudo[2006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 12 08:43:06 volumio sudo[2006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 12 08:43:06 volumio sudo[2006]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jun 12 08:43:06 volumio sudo[2009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 12 08:43:06 volumio sudo[2009]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 08:43:06 volumio systemd[1]: Started go-librespot Daemon. Jun 12 08:43:06 volumio sudo[2009]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio go-librespot[2017]: go-librespot daemon starting... Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: No need to fix Spotify hosts Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:43:06 volumio volumio[1204]: info: Upmpdcli Daemon Started Jun 12 08:43:06 volumio volumio[1204]: info: VolumeController:: Volume=41 Mute =false Jun 12 08:43:06 volumio volumio[1204]: info: CoreStateMachine::pushState Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioPushState Jun 12 08:43:06 volumio volumio[1204]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jun 12 08:43:06 volumio volumio[1204]: info: Starting Shairport Sync Jun 12 08:43:06 volumio volumio[1204]: info: Starting Shairport Sync Jun 12 08:43:06 volumio volumio[1204]: info: Starting Shairport Sync Jun 12 08:43:06 volumio mpd[2004]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 08:43:06 volumio mpd[2004]: output: No 'audio_output' defined in config file Jun 12 08:43:06 volumio sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 08:43:06 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 08:43:06 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio mpd[2004]: couldn't open play stream: No such file or directory Jun 12 08:43:06 volumio mpd[2004]: sndio_output: Error opening default sndio device Jun 12 08:43:06 volumio mpd[2004]: output: Successfully detected a alsa audio device Jun 12 08:43:06 volumio mpd[2004]: zeroconf: No global port, disabling zeroconf Jun 12 08:43:06 volumio systemd[1]: Started Music Player Daemon. Jun 12 08:43:06 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:43:06 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio sudo[1980]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:43:06 volumio sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 08:43:06 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:43:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 12 08:43:06 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 12 08:43:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 12 08:43:06 volumio volumio[1204]: info: Completed starting Core Plugins Jun 12 08:43:06 volumio volumio[1204]: info: ------------------------------------------- Jun 12 08:43:06 volumio volumio[1204]: info: ----- MyVolumio plugins startup ---- Jun 12 08:43:06 volumio volumio[1204]: info: ------------------------------------------- Jun 12 08:43:06 volumio volumio[1204]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 12 08:43:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 12 08:43:06 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 12 08:43:06 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 12 08:43:06 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 12 08:43:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 12 08:43:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 12 08:43:06 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Jun 12 08:43:06 volumio volumio[1204]: info: MPD running with PID2004 Jun 12 08:43:06 volumio volumio[1204]: ,establishing connection Jun 12 08:43:06 volumio volumio[1204]: info: Shairport-Sync Started Jun 12 08:43:06 volumio volumio[1204]: Error adding Membership: Error: addMembership EINVAL Jun 12 08:43:06 volumio volumio[1204]: info: Shairport-Sync Started Jun 12 08:43:06 volumio volumio[1204]: info: Shairport-Sync Started Jun 12 08:43:07 volumio volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Jun 12 08:43:07 volumio volumio[1204]: assert.ok(self.idling) Jun 12 08:43:07 volumio volumio[1204]: error: The expression evaluated to a falsy value: Jun 12 08:43:07 volumio volumio[1204]: assert.ok(self.idling) Jun 12 08:43:07 volumio volumio[1204]: error: MPD error: The expression evaluated to a falsy value: Jun 12 08:43:07 volumio volumio[1204]: assert.ok(self.idling) Jun 12 08:43:07 volumio volumio[1204]: error: The expression evaluated to a falsy value: Jun 12 08:43:07 volumio volumio[1204]: assert.ok(self.idling) Jun 12 08:43:07 volumio mpd[2004]: mixer: Failed to read mixer for 'default detected output': no such mixer control: PCM Jun 12 08:43:07 volumio volumio[1204]: error: updateQueue error: null Jun 12 08:43:07 volumio volumio[2010]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 12 08:43:07 volumio volumio[1204]: info: Volumio called home Jun 12 08:43:07 volumio volumio[2010]: ......................................................................................................++++ Jun 12 08:43:07 volumio volumio[1204]: 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: 2 Jun 12 08:43:07 volumio volumio[2010]: ........................................................................................................................................................++++ Jun 12 08:43:07 volumio volumio[2010]: e is 65537 (0x010001) Jun 12 08:43:07 volumio volumio[2010]: writing RSA key Jun 12 08:43:07 volumio go-librespot[2017]: time="2025-06-12T08:43:07+07:00" level=info msg="running go-librespot 0.2.0" Jun 12 08:43:07 volumio go-librespot[2017]: time="2025-06-12T08:43:07+07:00" level=debug msg="app state loaded" Jun 12 08:43:07 volumio go-librespot[2017]: time="2025-06-12T08:43:07+07:00" level=debug msg="stored credentials not found" Jun 12 08:43:07 volumio go-librespot[2017]: time="2025-06-12T08:43:07+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 12 08:43:07 volumio volumio[1204]: 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 Jun 12 08:43:08 volumio volumio-remote-updater[949]: [2025-06-12 08:43:08] [connect] Successful connection Jun 12 08:43:08 volumio volumio-remote-updater[949]: [2025-06-12 08:43:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749692588 101 Jun 12 08:43:08 volumio volumio[1204]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jun 12 08:43:09 volumio volumio[1204]: info: go-librespot daemon successfully initialized Jun 12 08:43:12 volumio mpd[2004]: exception: No database Jun 12 08:43:12 volumio mpd[2004]: exception: No database Jun 12 08:43:12 volumio volumio[1204]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Jun 12 08:43:12 volumio volumio[1204]: info: Initializing connection to go-librespot Websocket Jun 12 08:43:12 volumio go-librespot[2017]: time="2025-06-12T08:43:12+07:00" level=debug msg="new websocket client" Jun 12 08:43:12 volumio volumio[1204]: info: Connection to go-librespot Websocket established Jun 12 08:43:14 volumio go-librespot[2017]: time="2025-06-12T08:43:14+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jun 12 08:43:14 volumio go-librespot[2017]: time="2025-06-12T08:43:14+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jun 12 08:43:14 volumio go-librespot[2017]: time="2025-06-12T08:43:14+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jun 12 08:43:14 volumio go-librespot[2017]: time="2025-06-12T08:43:14+07:00" level=info msg="zeroconf server listening on port 43067" Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 12 08:43:15 volumio volumio[1204]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 12 08:43:19 volumio volumio[1204]: info: Adding plugin bluetooth to MyMusic Plugins Jun 12 08:43:19 volumio volumio[1204]: info: Adding plugin multiroom to MyMusic Plugins Jun 12 08:43:19 volumio volumio[1204]: info: Adding plugin metavolumio to MyMusic Plugins Jun 12 08:43:23 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 12 08:43:24 volumio volumio[1204]: info: Adding plugin cd_controller to MyMusic Plugins Jun 12 08:43:29 volumio volumio[1204]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 12 08:43:29 volumio volumio[1204]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 12 08:43:29 volumio volumio[1204]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 12 08:43:29 volumio volumio[1204]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 12 08:43:31 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 12 08:43:38 volumio volumio-remote-updater[949]: [2025-06-12 08:43:38] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jun 12 08:43:43 volumio volumio-remote-updater[949]: [2025-06-12 08:43:43] [connect] Successful connection Jun 12 08:43:52 volumio ntpd[1545]: Soliciting pool server 2001:c38:2000:180:d82a:1aff:fe38:f84b Jun 12 08:43:55 volumio volumio-remote-updater[949]: [2025-06-12 08:43:55] [connect] Successful connection Jun 12 08:43:57 volumio volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 12 08:43:57 volumio volumio[1204]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 12 08:43:57 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:57 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:43:57 volumio volumio[1204]: info: Starting MyVolumio Remote Streaming Endpoints Jun 12 08:43:57 volumio volumio[1204]: info: MyVolumio login type: Token Jun 12 08:43:57 volumio volumio[1204]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 12 08:43:57 volumio volumio[1204]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 12 08:44:04 volumio volumio[1204]: info: Starting Streaming Service Transparent Proxy Jun 12 08:44:04 volumio volumio[1204]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 12 08:44:04 volumio volumio[1204]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 12 08:44:04 volumio volumio[1204]: info: Streaming services startup Jun 12 08:44:04 volumio volumio[1204]: info: Starting Streaming Daemon Jun 12 08:44:04 volumio sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 12 08:44:04 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:44:04 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Jun 12 08:44:04 volumio volumio[1204]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 12 08:44:04 volumio volumio[1204]: info: Getting Spotify volume Jun 12 08:44:04 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:44:04 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:04 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 08:44:04 volumio volumio[1204]: STREAMING PROXY: Starting server on port 3245 Jun 12 08:44:04 volumio volumio[1204]: Node JS runtime: 14 Jun 12 08:44:06 volumio volumio[1204]: error: Cannot start Volumio Streaming Daemon Jun 12 08:44:06 volumio volumio[1204]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 12 08:44:06 volumio volumio[1204]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 12 08:44:06 volumio volumio[1204]: 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: 1 Jun 12 08:44:06 volumio volumio[1204]: 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: 2 Jun 12 08:44:06 volumio volumio[1204]: 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 Jun 12 08:44:06 volumio volumio[1204]: 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 Jun 12 08:44:06 volumio volumio[1204]: 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 Jun 12 08:44:06 volumio volumio[1204]: 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: 6 Jun 12 08:44:06 volumio volumio[1204]: 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: 7 Jun 12 08:44:06 volumio volumio[1204]: 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: 8 Jun 12 08:44:06 volumio volumio[1204]: 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: 9 Jun 12 08:44:06 volumio volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 12 08:44:06 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:06 volumio volumio[1204]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Jun 12 08:44:06 volumio volumio[1204]: SPOTIFY: SPOTIFY VOLUME undefined Jun 12 08:44:06 volumio volumio[1204]: SPOTIFY: VOLUMIO VOLUME 41 Jun 12 08:44:06 volumio volumio[1204]: info: Aligning Spotify Volume to Volumio Volume Jun 12 08:44:06 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:06 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:06 volumio volumio[1204]: info: Setting Spotify Volume from Volumio: 41 Jun 12 08:44:07 volumio volumio[1204]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 12 08:44:07 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 12 08:44:08 volumio volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 12 08:44:08 volumio volumio[1204]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Jun 12 08:44:08 volumio volumio[1204]: info: Sending Spotify command with payload to local API: /player/volume Jun 12 08:44:10 volumio volumio-remote-updater[949]: [2025-06-12 08:44:10] [connect] Successful connection Jun 12 08:44:10 volumio volumio-remote-updater[949]: [2025-06-12 08:44:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749692650 101 Jun 12 08:44:10 volumio volumio[1204]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12 Jun 12 08:44:11 volumio volumio[1204]: 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: 13 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Info Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 08:44:11 volumio volumio[1204]: info: Discovery: Getting this device information Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: Listing playlists Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Info Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 08:44:11 volumio volumio[1204]: info: Discovery: Getting this device information Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Info Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 08:44:11 volumio volumio[1204]: info: Discovery: Getting this device information Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 08:44:11 volumio volumio[1204]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Version Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Info Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 08:44:11 volumio volumio[1204]: info: Discovery: Getting this device information Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 12 08:44:11 volumio volumio[1204]: info: Received Get System Info Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 12 08:44:11 volumio volumio[1204]: info: Discovery: Getting this device information Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 08:44:11 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:11 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:11 volumio volumio[1204]: info: Listing playlists Jun 12 08:44:12 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 08:44:12 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 12 08:44:12 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 12 08:44:12 volumio volumio[1204]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 12 08:44:12 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:12 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:14 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 08:44:17 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 08:44:21 volumio volumio[1204]: info: CoreCommandRouter::volumioPlay Jun 12 08:44:21 volumio volumio[1204]: info: CoreStateMachine::play index undefined Jun 12 08:44:21 volumio volumio[1204]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 08:44:21 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:22 volumio volumio[1204]: info: CoreCommandRouter::volumioGetQueue Jun 12 08:44:22 volumio volumio[1204]: info: CoreStateMachine::getQueue Jun 12 08:44:22 volumio volumio[1204]: info: CorePlayQueue::getQueue Jun 12 08:44:24 volumio volumio[1204]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 12 08:44:24 volumio volumio[1204]: info: Completed starting MyVolumio Plugin Jun 12 08:44:24 volumio volumio[1204]: [Metrics] CommandRouter: 110s 46.54ms Jun 12 08:44:24 volumio volumio[1204]: info: CoreCommandRouter::volumiosetStartupVolume Jun 12 08:44:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 08:44:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:24 volumio volumio[1204]: info: CoreCommandRouter::Close All Modals sent Jun 12 08:44:24 volumio volumio[1204]: info: CoreCommandRouter::Close All Modals sent Jun 12 08:44:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 12 08:44:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 08:44:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 12 08:44:29 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Jun 12 08:44:29 volumio volumio[1204]: info: CorePlayQueue::getTrack 0 Jun 12 08:44:31 volumio volumio[1204]: info: BOOT COMPLETED Jun 12 08:44:32 volumio volumio[1204]: info: MyVolumio token set successfully Jun 12 08:44:32 volumio volumio[1204]: info: MYVOLUMIO: Adding device Jun 12 08:44:32 volumio volumio[1204]: info: MYVOLUMIO: Evaluating Server Jun 12 08:44:33 volumio volumio[1204]: info: MyVolumio status changed Jun 12 08:44:33 volumio volumio[1204]: info: Streaming services startup Jun 12 08:44:33 volumio volumio[1204]: info: Starting Streaming Daemon Jun 12 08:44:33 volumio volumio[1204]: info: Removing browser output: myVolumio user plan is not superstar Jun 12 08:44:33 volumio volumio[1204]: info: Removing audio output: Jun 12 08:44:33 volumio volumio[1204]: info: Stoppping Tunnel 1 Jun 12 08:44:33 volumio sudo[2539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 12 08:44:33 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:44:33 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Jun 12 08:44:33 volumio volumio[1204]: error: Cannot start Volumio Streaming Daemon Jun 12 08:44:33 volumio volumio[1204]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 12 08:44:33 volumio volumio[1204]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 12 08:44:33 volumio sudo[2542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 12 08:44:33 volumio sudo[2542]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 08:44:33 volumio sudo[2542]: pam_unix(sudo:session): session closed for user root Jun 12 08:44:33 volumio volumio[1204]: info: Remote SSH Stopped Jun 12 08:44:33 volumio volumio[1204]: info: Setting Geolocation for MyVolumio to as1 Jun 12 08:44:33 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:33 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:33 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:33 volumio volumio[1204]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 12 08:44:34 volumio volumio[1204]: info: Updating MyVolumio device info Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: info: Retrieving Cloud Streaming UI Jun 12 08:44:34 volumio volumio[1204]: info: Getting Tidal Cloud Configuration Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: info: Getting Qobuz Cloud Configuration Jun 12 08:44:34 volumio volumio[1204]: info: Asking plugin for UI Config Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: info: Getting Spotify Cloud Configuration Jun 12 08:44:34 volumio volumio[1204]: info: Asking plugin for UI Config Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: info: Saving Spotify Acccount Jun 12 08:44:34 volumio volumio[1204]: info: Got Tidal Cloud Configuration Jun 12 08:44:34 volumio volumio[1204]: info: Got it Jun 12 08:44:34 volumio volumio[1204]: info: Got it Jun 12 08:44:34 volumio volumio[1204]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 08:44:34 volumio volumio[1204]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::volumioGetBrowseSources Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 08:44:34 volumio mpd[2004]: exception: No database Jun 12 08:44:34 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 12 08:44:34 volumio volumio[1204]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 12 08:44:37 volumio volumio[1204]: info: MYVOLUMIO: Adding device Jun 12 08:44:37 volumio volumio[1204]: info: MYVOLUMIO: Evaluating Server Jun 12 08:44:38 volumio volumio[1204]: info: Setting Geolocation for MyVolumio to as1 Jun 12 08:44:38 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:38 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:38 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:38 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 12 08:44:38 volumio mpd[2004]: exception: No database Jun 12 08:44:38 volumio volumio[1204]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 12 08:44:39 volumio volumio[1204]: info: Updating MyVolumio device info Jun 12 08:44:39 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:39 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:39 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 08:44:39 volumio volumio[1204]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 12 08:44:41 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 12 08:44:41 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 12 08:44:41 volumio volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 08:44:41 volumio volumio[1204]: Error: getaddrinfo -3007 Jun 12 08:44:41 volumio volumio[1204]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jun 12 08:44:41 volumio volumio[1204]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jun 12 08:44:41 volumio volumio[1204]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jun 12 08:44:41 volumio volumio[1204]: code: -3007, Jun 12 08:44:41 volumio volumio[1204]: errno: -3007, Jun 12 08:44:41 volumio volumio[1204]: syscall: 'getaddrinfo' Jun 12 08:44:41 volumio volumio[1204]: } Jun 12 08:44:41 volumio volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 08:44:41 volumio sudo[2589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 08:43 Jun 12 08:44:41 volumio sudo[2589]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"