-- Logs begin at Mon 2026-08-31 14:46:45 CST, end at Mon 2026-08-31 14:48:43 CST. -- Aug 31 14:47:00 volumio-x86 sudo[1420]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Loading plugin "i2s_dacs"... Aug 31 14:47:00 volumio-x86 volumio[1128]: info: I2S DAC not set, start Auto-detection Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Loading plugin "volumiodiscovery"... Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** For more information see Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 14:47:00 volumio-x86 volumio[1128]: *** WARNING *** For more information see Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** For more information see Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 14:47:00 volumio-x86 node[1128]: *** WARNING *** For more information see Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Discovery: Started advertising with name: Volumio-x86 Aug 31 14:47:00 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Plugin calmradio is not enabled Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Plugin jellyfin is not enabled Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Plugin spop is not enabled Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Plugin youtube2 is not enabled Aug 31 14:47:00 volumio-x86 volumio[1128]: info: Loading plugin "ytcr"... Aug 31 14:47:00 volumio-x86 volumio-time-update[831]: volumio-time-update-util: Setting system time to: 2026-08-31 14:47:00 Aug 31 14:47:00 volumio-x86 sudo[1468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-08-31 14:47:00 Aug 31 14:47:00 volumio-x86 sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:00 volumio-x86 dbus-daemon[823]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=1469 comm="timedatectl set-time 2026-08-31 14:47:00 ") Aug 31 14:47:00 volumio-x86 systemd[1]: Starting Time & Date Service... Aug 31 14:47:00 volumio-x86 dbus-daemon[823]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 14:47:00 volumio-x86 systemd[1]: Started Time & Date Service. Aug 31 14:47:00 volumio-x86 systemd-timedated[1470]: Changed local time to Mon Aug 31 14:47:00 2026 Aug 31 14:47:00 volumio-x86 sudo[1468]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:00 volumio-x86 volumio-time-update[831]: volumio-time-update-util: System time updated successfully. Aug 31 14:47:00 volumio-x86 systemd[1]: Started Volumio Time Update Utility. Aug 31 14:47:00 volumio-x86 systemd[1]: Reached target Multi-User System. Aug 31 14:47:00 volumio-x86 systemd[1]: Reached target Graphical Interface. Aug 31 14:47:00 volumio-x86 systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 31 14:47:00 volumio-x86 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 31 14:47:00 volumio-x86 systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 31 14:47:00 volumio-x86 systemd[1]: Startup finished in 3.605s (firmware) + 2.178s (loader) + 10.316s (kernel) + 15.789s (userspace) = 31.889s. Aug 31 14:47:03 volumio-x86 dhcpcd[1011]: wlan0: using IPv4LL address 169.254.104.41 Aug 31 14:47:03 volumio-x86 dhcpcd[1011]: wlan0: adding route to 169.254.0.0/16 Aug 31 14:47:03 volumio-x86 avahi-daemon[822]: Registering new address record for 169.254.104.41 on wlan0.IPv4. Aug 31 14:47:03 volumio-x86 volumio[1128]: info: Plugin ytmusic is not enabled Aug 31 14:47:03 volumio-x86 volumio[1128]: info: Loading plugin "autostart"... Aug 31 14:47:04 volumio-x86 volumio[1128]: info: Applying required configuration parameters for plugin autostart Aug 31 14:47:04 volumio-x86 volumio[1128]: info: AutoStart - onVolumioStart - read config.json Aug 31 14:47:04 volumio-x86 volumio[1128]: info: Loading plugin "now_playing"... Aug 31 14:47:05 volumio-x86 ntpd[1401]: Listen normally on 5 wlan0 169.254.104.41:123 Aug 31 14:47:05 volumio-x86 ntpd[1401]: new interface(s) found: waking up resolver Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Loading plugin "peppy_screensaver"... Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Applying required configuration parameters for plugin peppy_screensaver Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Loading plugin "outputs"... Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Loading plugin "albumart"... Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Plugin example_plugin is not enabled Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Loading plugin "inputs"... Aug 31 14:47:05 volumio-x86 volumio[1128]: info: Loading plugin "updater_comm"... Aug 31 14:47:06 volumio-x86 volumio[1128]: Forking 3 albumart workers Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Plugin mpdemulation is not enabled Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Loading plugin "rest_api"... Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Loading plugin "websocket"... Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Starting Socket.io Server version 2.3.0 Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Plugin 80s80s is not enabled Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Loading plugin "RoonBridge"... Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Applying required configuration parameters for plugin RoonBridge Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Loading plugin "minidlna"... Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Applying required configuration parameters for plugin minidlna Aug 31 14:47:06 volumio-x86 volumio[1128]: Starting albumart workers Aug 31 14:47:06 volumio-x86 volumio[1128]: Starting albumart workers Aug 31 14:47:06 volumio-x86 volumio[1128]: Starting albumart workers Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Plugin podcast is not enabled Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Plugin Systeminfo is not enabled Aug 31 14:47:06 volumio-x86 volumio[1128]: info: Loading i18n strings for locale zh Aug 31 14:47:06 volumio-x86 volumio[1128]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Aug 31 14:47:06 volumio-x86 volumio[1128]: Updating browse sources language Aug 31 14:47:06 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::initPlayerControls Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: Express server listening on port 3000 Aug 31 14:47:07 volumio-x86 volumio[1128]: [Metrics] WebUI: 12s 779.71ms Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Setting Device type: x86 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::resetVolumioState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::getcurrentVolume Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Completed loading Core Plugins Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Preparing to generate the ALSA configuration file Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Volumio Network Manager: Network status updated: 1 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 31 14:47:07 volumio-x86 volumio[1128]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Reading ALSA contributions from plugins. Aug 31 14:47:07 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: VolumeController:: Volume=98 Mute =false Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::updateTrackBlock Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrackBlock Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Reloading queue from file Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::setRepeat null single undefined Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::setRandom null Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: VolumeController:: Volume=98 Mute =false Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Asound.conf file unchanged, so no further update is needed Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Output device has changed, restarting MPD Aug 31 14:47:07 volumio-x86 sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 14:47:07 volumio-x86 sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:07 volumio-x86 sudo[1556]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Output device has changed, restarting Shairport Sync Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:07 volumio-x86 sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 14:47:07 volumio-x86 sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:07 volumio-x86 systemd[1]: Stopping Music Player Daemon... Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: ___________ START PLUGINS ___________ Aug 31 14:47:07 volumio-x86 volumio[1128]: info: ControllerMpd::onStart: Initializing MPD Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Creating MPD Configuration file Aug 31 14:47:07 volumio-x86 volumio-remote-updater[828]: [2026-08-31 14:47:07] [connect] Successful connection Aug 31 14:47:07 volumio-x86 sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 14:47:07 volumio-x86 sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 14:47:07 volumio-x86 volumio[1128]: info: [1788158827742] CoreMusicLibrary::Adding element 媒体服务器 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:47:07 volumio-x86 sudo[1565]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:07 volumio-x86 sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 14:47:07 volumio-x86 sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:07 volumio-x86 systemd[1]: mpd.service: Succeeded. Aug 31 14:47:07 volumio-x86 systemd[1]: Stopped Music Player Daemon. Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:07 volumio-x86 systemd[1]: Starting Music Player Daemon... Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 14:47:07 volumio-x86 volumio[1128]: info: [1788158827854] CoreMusicLibrary::Adding element Last_100 Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 14:47:07 volumio-x86 volumio[1128]: info: [1788158827857] CoreMusicLibrary::Adding element Webradio Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:47:07 volumio-x86 volumio[1128]: info: Initializing BBC Radios Aug 31 14:47:07 volumio-x86 sudo[1573]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 14:47:07 volumio-x86 sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:07 volumio-x86 sudo[1573]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:47:07 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:07 volumio-x86 volumio[1128]: info: AutoStart - onStart Aug 31 14:47:08 volumio-x86 volumio[1128]: info: [now-playing] ConfigUpdater: config is up to date. Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Loading i18n strings for locale zh Aug 31 14:47:08 volumio-x86 volumio[1128]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Aug 31 14:47:08 volumio-x86 volumio[1128]: info: /tmp/myfifo created Aug 31 14:47:08 volumio-x86 volumio[1128]: info: /tmp/myfifosa created Aug 31 14:47:08 volumio-x86 sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Aug 31 14:47:08 volumio-x86 sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:08 volumio-x86 sudo[1598]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:08 volumio-x86 volumio[1128]: info: snd-dummy loaded Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Loading i18n strings for locale zh Aug 31 14:47:08 volumio-x86 volumio[1128]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Aug 31 14:47:08 volumio-x86 sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Aug 31 14:47:08 volumio-x86 sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Volumio Calling Home Aug 31 14:47:08 volumio-x86 systemd[1]: Started RoonBridge. Aug 31 14:47:08 volumio-x86 sudo[1628]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:08 volumio-x86 volumio[1128]: info: [now-playing] App is listening on port 4004. Aug 31 14:47:08 volumio-x86 volumio[1128]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Discovery: adding 842803b6-f2c1-4d6c-b97d-d9db7c8587d2 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Discovery: Found device Volumio-x86 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Discovery: this is already registered, 842803b6-f2c1-4d6c-b97d-d9db7c8587d2 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: Discovery: Found device Volumio-x86 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:08 volumio-x86 sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:47:08 volumio-x86 sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:08 volumio-x86 sudo[1645]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:08 volumio-x86 sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:47:08 volumio-x86 sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:08 volumio-x86 volumio[1128]: info: minidlna: Found miniDLNA version 1.2.1 Aug 31 14:47:08 volumio-x86 sudo[1648]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:08 volumio-x86 volumio[1128]: info: minidlna: Starting minidlna.service Aug 31 14:47:08 volumio-x86 volumio[1128]: info: MPD Permissions set Aug 31 14:47:08 volumio-x86 sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 31 14:47:08 volumio-x86 sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:08 volumio-x86 volumio-remote-updater[828]: [2026-08-31 14:47:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1788158827 101 Aug 31 14:47:08 volumio-x86 volumio[1128]: 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: 2 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:08 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Aug 31 14:47:08 volumio-x86 sudo[1652]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 mpd[1584]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 14:47:09 volumio-x86 mpd[1584]: output: No 'audio_output' defined in config file Aug 31 14:47:09 volumio-x86 mpd[1584]: output: Successfully detected a sndio audio device Aug 31 14:47:09 volumio-x86 mpd[1584]: zeroconf: No global port, disabling zeroconf Aug 31 14:47:09 volumio-x86 systemd[1]: Started Music Player Daemon. Aug 31 14:47:09 volumio-x86 sudo[1568]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 sudo[1559]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Aug 31 14:47:09 volumio-x86 sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 sudo[1674]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 volumio[1128]: info: minidlna: systemctl start minidlna.service succeeded. Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Volumio called home Aug 31 14:47:09 volumio-x86 sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 14:47:09 volumio-x86 sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 sudo[1681]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Starting Shairport Sync Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Starting Shairport Sync Aug 31 14:47:09 volumio-x86 sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 14:47:09 volumio-x86 sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Starting Shairport Sync Aug 31 14:47:09 volumio-x86 sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 14:47:09 volumio-x86 sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Starting Shairport Sync Aug 31 14:47:09 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 14:47:09 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Aug 31 14:47:09 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 14:47:09 volumio-x86 sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:09 volumio-x86 sudo[1686]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 volumio[1128]: info: MPD Permissions set Aug 31 14:47:09 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 14:47:09 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 31 14:47:09 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Aug 31 14:47:09 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 sudo[1692]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 sudo[1688]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 14:47:09 volumio-x86 sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 14:47:09 volumio-x86 sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 14:47:09 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Aug 31 14:47:09 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 14:47:09 volumio-x86 volumio[1128]: error: MPD error: The expression evaluated to a falsy value: Aug 31 14:47:09 volumio-x86 volumio[1128]: assert.ok(self.idling) Aug 31 14:47:09 volumio-x86 volumio[1128]: error: The expression evaluated to a falsy value: Aug 31 14:47:09 volumio-x86 volumio[1128]: assert.ok(self.idling) Aug 31 14:47:09 volumio-x86 sudo[1696]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. Aug 31 14:47:09 volumio-x86 sudo[1699]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Shairport-Sync Started Aug 31 14:47:09 volumio-x86 volumio[1128]: Error adding Membership: Error: addMembership EINVAL Aug 31 14:47:09 volumio-x86 volumio[1128]: info: MPD running with PID1584 Aug 31 14:47:09 volumio-x86 volumio[1128]: ,establishing connection Aug 31 14:47:09 volumio-x86 volumio[1128]: error: updateQueue error: null Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Upmpdcli Daemon Started Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Shairport-Sync Started Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Shairport-Sync Started Aug 31 14:47:09 volumio-x86 volumio[1128]: info: Shairport-Sync Started Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 RoonBridge[1632]: 00:00:00.012 Warn: get lock file path: /tmp/.rnbgem0- Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:47:09 volumio-x86 volumio[1128]: error: updateQueue error: null Aug 31 14:47:09 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 31 14:47:09 volumio-x86 volumio[1704]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 31 14:47:09 volumio-x86 sudo[1729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 14:47:09 volumio-x86 sudo[1729]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 systemd[1]: Stopping Music Player Daemon... Aug 31 14:47:09 volumio-x86 systemd[1]: mpd.service: Succeeded. Aug 31 14:47:09 volumio-x86 systemd[1]: Stopped Music Player Daemon. Aug 31 14:47:09 volumio-x86 systemd[1]: Starting Music Player Daemon... Aug 31 14:47:09 volumio-x86 sudo[1731]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 14:47:09 volumio-x86 sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:09 volumio-x86 sudo[1731]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.539 Trace: [childprocess] using unix child process Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.606 Debug: PathForResource, filename: ../.update Aug 31 14:47:10 volumio-x86 volumio[1704]: .......++++ Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.650 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.658 Debug: PathForResource, filename: RAATServer Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.658 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.659 Debug: PathForResource, filename: RoonBridgeHelper Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.659 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: Initializing Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.717 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: 00:00:00.773 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: Not Running (.o) Aug 31 14:47:10 volumio-x86 volumio[1704]: ............++++ Aug 31 14:47:10 volumio-x86 volumio[1704]: e is 65537 (0x010001) Aug 31 14:47:10 volumio-x86 volumio[1704]: writing RSA key Aug 31 14:47:10 volumio-x86 RoonBridge[1632]: Running Aug 31 14:47:11 volumio-x86 RoonBridge[1632]: 00:00:00.028 Warn: get lock file path: /tmp/.rnbhgem0- Aug 31 14:47:11 volumio-x86 mpd[1733]: Aug 31 14:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 14:47:11 volumio-x86 systemd[1]: Started Music Player Daemon. Aug 31 14:47:11 volumio-x86 sudo[1729]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:11 volumio-x86 volumio[1128]: error: updateQueue error: null Aug 31 14:47:12 volumio-x86 sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Aug 31 14:47:12 volumio-x86 sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:12 volumio-x86 sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:12 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Aug 31 14:47:12 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 31 14:47:12 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Aug 31 14:47:12 volumio-x86 volumio[1128]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds Aug 31 14:47:14 volumio-x86 volumio[1128]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 31 14:47:17 volumio-x86 sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Aug 31 14:47:17 volumio-x86 sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:17 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Aug 31 14:47:17 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 31 14:47:17 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Aug 31 14:47:17 volumio-x86 sudo[1816]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:17 volumio-x86 volumio[1128]: info: Cannot mount NAS local at system boot, trial number 3 ,retrying in 5 seconds Aug 31 14:47:17 volumio-x86 systemd[1]: systemd-fsckd.service: Succeeded. Aug 31 14:47:18 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:18 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:19 volumio-x86 systemd[1]: systemd-hostnamed.service: Succeeded. Aug 31 14:47:22 volumio-x86 sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Aug 31 14:47:22 volumio-x86 sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:22 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Aug 31 14:47:22 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 31 14:47:22 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Aug 31 14:47:22 volumio-x86 sudo[1848]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:22 volumio-x86 volumio[1128]: info: Cannot mount NAS local at system boot, trial number 4 ,retrying in 5 seconds Aug 31 14:47:27 volumio-x86 sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //127.0.0.1/Internal Storage/musics /mnt/NAS/local Aug 31 14:47:27 volumio-x86 sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:27 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Aug 31 14:47:27 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Aug 31 14:47:27 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111 Aug 31 14:47:27 volumio-x86 sudo[1855]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:27 volumio-x86 volumio[1128]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Aug 31 14:47:28 volumio-x86 volumio[1128]: info: AutoStart - Plugin is starting Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetQueue Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::getQueue Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getQueue Aug 31 14:47:28 volumio-x86 volumio[1128]: info: AutoStart - start playing Aug 31 14:47:28 volumio-x86 volumio[1128]: info: AutoStart - start playing with no specific position Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPlay Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::play index 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::stop Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::play index undefined Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::startPlaybackTimer Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3 Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand stop took 6 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Aug 31 14:47:28 volumio-x86 volumio[1128]: ---------------------------- MPD announces system playlist update Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Ignoring MPD Status Update Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand clear took 8 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3" Aug 31 14:47:28 volumio-x86 volumio[1128]: error: updateQueue error: null Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Aug 31 14:47:28 volumio-x86 volumio[1128]: ---------------------------- MPD announces system playlist update Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Ignoring MPD Status Update Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ------------------------------ 13ms Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand add "INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3" took 8 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand play Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ------------------------------ 9ms Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand play took 8 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Aug 31 14:47:28 volumio-x86 volumio[1128]: ---------------------------- MPD announces state update: player Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ControllerMpd::getState Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Aug 31 14:47:28 volumio-x86 kernel: intel_sst_acpi 80860F28:00: FW Version 01.0c.00.01 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Aug 31 14:47:28 volumio-x86 volumio[1128]: ---------------------------- MPD announces state update: player Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand status took 342 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ControllerMpd::getState Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseState Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand status took 4 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseState Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseTrackInfo Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseTrackInfo Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ControllerMpd::pushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::servicePushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: CURRENT POSITION 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState stateService play Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState currentStatus stop Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ControllerMpd::pushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::servicePushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: STATE SERVICE {"status":"play","position":0,"seek":443,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} Aug 31 14:47:28 volumio-x86 volumio[1128]: verbose: CURRENT POSITION 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState stateService play Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState currentStatus play Aug 31 14:47:28 volumio-x86 volumio[1128]: info: Received an update from plugin. extracting info from payload Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ------------------------------ 372ms Aug 31 14:47:28 volumio-x86 volumio[1128]: info: ------------------------------ 39ms Aug 31 14:47:29 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:29 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:29 volumio-x86 sudo[1882]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Aug 31 14:47:29 volumio-x86 sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:30 volumio-x86 systemd[1]: systemd-timedated.service: Succeeded. Aug 31 14:47:34 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 4 Aug 31 14:47:34 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:47:34 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:47:38 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:47:38 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:38 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:47:38 volumio-x86 volumio[1128]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Aug 31 14:47:38 volumio-x86 volumio[1128]: info: ------------------------------------------- Aug 31 14:47:38 volumio-x86 volumio[1128]: info: ----- MyVolumio plugins startup ---- Aug 31 14:47:38 volumio-x86 volumio[1128]: info: ------------------------------------------- Aug 31 14:47:38 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 14:47:47 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 14:47:48 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 14:47:48 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 14:47:48 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:48 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:47:48 volumio-x86 volumio[1128]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 14:47:49 volumio-x86 volumio[1128]: info: MyVolumio login type: Token Aug 31 14:47:49 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 14:47:49 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 14:47:50 volumio-x86 volumio[1128]: info: Starting Streaming Service Transparent Proxy Aug 31 14:47:50 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 14:47:50 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 14:47:50 volumio-x86 volumio[1128]: info: Streaming services startup Aug 31 14:47:50 volumio-x86 volumio[1128]: info: Starting Streaming Daemon Aug 31 14:47:50 volumio-x86 sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 14:47:50 volumio-x86 sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:47:50 volumio-x86 sudo[1987]: pam_unix(sudo:session): session closed for user root Aug 31 14:47:50 volumio-x86 volumio[1128]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 14:47:50 volumio-x86 volumio[1128]: error: Cannot start Volumio Streaming Daemon Aug 31 14:47:50 volumio-x86 volumio[1128]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 14:47:50 volumio-x86 volumio[1128]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 14:47:50 volumio-x86 volumio[1128]: STREAMING PROXY: Starting server on port 3245 Aug 31 14:47:50 volumio-x86 volumio[1128]: Node JS runtime: 14 Aug 31 14:48:03 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:48:03 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 31 14:48:04 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:04 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 14:48:08 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 14:48:10 volumio-x86 volumio[1128]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 31 14:48:10 volumio-x86 volumio[1128]: info: Completed starting MyVolumio Plugin Aug 31 14:48:10 volumio-x86 volumio[1128]: [Metrics] CommandRouter: 75s 137.33ms Aug 31 14:48:10 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumiosetStartupVolume Aug 31 14:48:10 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 14:48:10 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 14:48:10 volumio-x86 volumio[1128]: info: CoreCommandRouter::Close All Modals sent Aug 31 14:48:10 volumio-x86 volumio[1128]: info: CoreCommandRouter::Close All Modals sent Aug 31 14:48:10 volumio-x86 volumio[1128]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Aug 31 14:48:10 volumio-x86 volumio[1128]: aplay: main:828: audio open error: Device or resource busy Aug 31 14:48:11 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 31 14:48:11 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 14:48:11 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 31 14:48:17 volumio-x86 volumio[1128]: info: BOOT COMPLETED Aug 31 14:48:20 volumio-x86 volumio[1128]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 31 14:48:20 volumio-x86 volumio[1128]: info: Retrying Login Due to Network Error Aug 31 14:48:27 volumio-x86 sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:48:27 volumio-x86 sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:48:27 volumio-x86 sudo[2051]: pam_unix(sudo:session): session closed for user root Aug 31 14:48:27 volumio-x86 sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:48:27 volumio-x86 sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:48:27 volumio-x86 sudo[2054]: pam_unix(sudo:session): session closed for user root Aug 31 14:48:27 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to 192.168.8.38 from 192.168.8.34 UA: Mozilla/5.0 (Linux; U; Android 16; zh-CN; PKW110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/123.0.6312.80 UCBrowser/18.9.8.1524 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 14:48:28 volumio-x86 volumio[1128]: info: Received Get System Info Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:48:28 volumio-x86 volumio[1128]: info: Discovery: Getting this device information Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:28 volumio-x86 volumio[1128]: info: Listing playlists Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:48:28 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:48:29 volumio-x86 sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:48:29 volumio-x86 sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:48:29 volumio-x86 sudo[2071]: pam_unix(sudo:session): session closed for user root Aug 31 14:48:29 volumio-x86 sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:48:29 volumio-x86 sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 14:48:29 volumio-x86 sudo[2074]: pam_unix(sudo:session): session closed for user root Aug 31 14:48:30 volumio-x86 volumio[1128]: info: MyVolumio login type: Token Aug 31 14:48:30 volumio-x86 volumio[1128]: verbose: New Socket.io Connection to 192.168.8.38 from 192.168.8.34 UA: Mozilla/5.0 (Linux; U; Android 16; zh-CN; PKW110 Build/BP2A.250605.015) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/123.0.6312.80 UCBrowser/18.9.8.1524 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 14:48:31 volumio-x86 volumio[1128]: info: Received Get System Info Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:48:31 volumio-x86 volumio[1128]: info: Discovery: Getting this device information Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:31 volumio-x86 volumio[1128]: info: Listing playlists Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:48:31 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:48:32 volumio-x86 volumio[1128]: info: Received Get System Info Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:48:32 volumio-x86 volumio[1128]: info: Discovery: Getting this device information Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioGetState Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:32 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:48:34 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 14:48:39 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:48:40 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 14:48:40 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::pause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::stPlaybackTimer Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::servicePause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreCommandRouter::servicePause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: ControllerMpd::pause Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: Aug 31 14:48:43 volumio-x86 volumio[1128]: ---------------------------- MPD announces state update: player Aug 31 14:48:43 volumio-x86 volumio[1128]: info: sendMpdCommand pause took 10 milliseconds Aug 31 14:48:43 volumio-x86 volumio[1128]: info: ControllerMpd::getState Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand status Aug 31 14:48:43 volumio-x86 volumio[1128]: info: sendMpdCommand status took 5 milliseconds Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseState Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 14:48:43 volumio-x86 volumio[1128]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: ControllerMpd::parseTrackInfo Aug 31 14:48:43 volumio-x86 volumio[1128]: info: ControllerMpd::pushState Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreCommandRouter::servicePushState Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":75493,"duration":263,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"卷珠帘","artist":"童丽","album":"古筝遇见童丽II","uri":"INTERNAL/musics/古筝遇见童丽II/童丽-卷珠帘.mp3","trackType":"mp3"} Aug 31 14:48:43 volumio-x86 volumio[1128]: verbose: CURRENT POSITION 0 Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState stateService pause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::syncState currentStatus pause Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::pushState Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CorePlayQueue::getTrack 0 Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreCommandRouter::volumioPushState Aug 31 14:48:43 volumio-x86 volumio[1128]: info: CoreStateMachine::stPlaybackTimer Aug 31 14:48:43 volumio-x86 volumio[1128]: info: ------------------------------ 37ms Aug 31 14:48:43 volumio-x86 volumio[1128]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:48:43 volumio-x86 volumio[1128]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Aug 31 14:48:43 volumio-x86 volumio[1128]: at Object.readdirSync (fs.js:1048:3) Aug 31 14:48:43 volumio-x86 volumio[1128]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Aug 31 14:48:43 volumio-x86 volumio[1128]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Aug 31 14:48:43 volumio-x86 volumio[1128]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Aug 31 14:48:43 volumio-x86 volumio[1128]: errno: -20, Aug 31 14:48:43 volumio-x86 volumio[1128]: syscall: 'scandir', Aug 31 14:48:43 volumio-x86 volumio[1128]: code: 'ENOTDIR', Aug 31 14:48:43 volumio-x86 volumio[1128]: path: '/tmp/peppyrunning' Aug 31 14:48:43 volumio-x86 volumio[1128]: } Aug 31 14:48:43 volumio-x86 volumio[1128]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:48:43 volumio-x86 sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-31 14:47 Aug 31 14:48:43 volumio-x86 sudo[2104]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"