-- Logs begin at Thu 2026-10-01 08:33:00 CST, end at Sun 2026-10-11 17:57:51 CST. --
Oct 11 17:56:00 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Oct 11 17:56:00 volumio-x86 systemd[1]: mpd.service: Succeeded.
Oct 11 17:56:00 volumio-x86 systemd[1]: Stopped Music Player Daemon.
Oct 11 17:56:00 volumio-x86 systemd[1]: Starting Music Player Daemon...
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:00 volumio-x86 sudo[3643]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 17:56:00 volumio-x86 sudo[3643]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:00 volumio-x86 sudo[3643]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: [1791712560170] CoreMusicLibrary::Adding element Last_100
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: [1791712560175] CoreMusicLibrary::Adding element Webradio
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Initializing BBC Radios
Oct 11 17:56:00 volumio-x86 sudo[3544]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: AutoStart - onStart
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Loading i18n strings for locale zh
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: /tmp/myfifo created
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: /tmp/myfifosa created
Oct 11 17:56:00 volumio-x86 sudo[3682]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Oct 11 17:56:00 volumio-x86 sudo[3682]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:00 volumio-x86 sudo[3682]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: snd-dummy loaded
Oct 11 17:56:00 volumio-x86 volumio[3506]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Volumio Calling Home
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Discovery: Found device Volumio-x86
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Discovery: Getting this device information
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: MPD Permissions set
Oct 11 17:56:00 volumio-x86 volumio[3506]: 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
Oct 11 17:56:00 volumio-x86 volumio[3506]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Discovery: Getting this device information
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Received Get System Info
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Discovery: Getting this device information
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:00 volumio-x86 volumio[3506]: info: Listing playlists
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Starting Shairport Sync
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Starting Shairport Sync
Oct 11 17:56:01 volumio-x86 sudo[3704]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:01 volumio-x86 sudo[3704]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Starting Shairport Sync
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:56:01 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 sudo[3707]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:01 volumio-x86 sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 sudo[3710]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:56:01 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 11 17:56:01 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:01 volumio-x86 sudo[3704]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 11 17:56:01 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Volumio called home
Oct 11 17:56:01 volumio-x86 sudo[3707]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Oct 11 17:56:01 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 11 17:56:01 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:56:01 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:01 volumio-x86 sudo[3710]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Shairport-Sync Started
Oct 11 17:56:01 volumio-x86 volumio[3506]: Error adding Membership: Error: addMembership EINVAL
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Oct 11 17:56:01 volumio-x86 sudo[3717]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Oct 11 17:56:01 volumio-x86 sudo[3717]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3717]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 mpd[3650]: Oct 11 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 17:56:01 volumio-x86 sudo[3725]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:01 volumio-x86 systemd[1]: Started Music Player Daemon.
Oct 11 17:56:01 volumio-x86 sudo[3725]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3628]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 sudo[3618]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 sudo[3725]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 sudo[3734]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:01 volumio-x86 sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3734]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 sudo[3740]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:01 volumio-x86 sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3740]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 sudo[3745]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:56:01 volumio-x86 sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3745]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Shairport-Sync Started
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Shairport-Sync Started
Oct 11 17:56:01 volumio-x86 sudo[3748]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:56:01 volumio-x86 sudo[3748]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:01 volumio-x86 sudo[3748]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Completed starting Core Plugins
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: -------------------------------------------
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: -----    MyVolumio plugins startup     ----
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: -------------------------------------------
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: MPD running with PID3650
Oct 11 17:56:01 volumio-x86 volumio[3506]:  ,establishing connection
Oct 11 17:56:01 volumio-x86 volumio[3506]: error: MPD error: The expression evaluated to a falsy value:
Oct 11 17:56:01 volumio-x86 volumio[3506]:   assert.ok(self.idling)
Oct 11 17:56:01 volumio-x86 volumio[3506]: error: The expression evaluated to a falsy value:
Oct 11 17:56:01 volumio-x86 volumio[3506]:   assert.ok(self.idling)
Oct 11 17:56:01 volumio-x86 volumio[3506]: error: MPD error: The expression evaluated to a falsy value:
Oct 11 17:56:01 volumio-x86 volumio[3506]:   assert.ok(self.idling)
Oct 11 17:56:01 volumio-x86 volumio[3506]: error: The expression evaluated to a falsy value:
Oct 11 17:56:01 volumio-x86 volumio[3506]:   assert.ok(self.idling)
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Oct 11 17:56:01 volumio-x86 volumio[3506]: error: updateQueue error: null
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: Discovery: Getting this device information
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:01 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:02 volumio-x86 volumio[3506]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.93 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 10
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: Received Get System Info
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: Discovery: Getting this device information
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: Listing playlists
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreCommandRouter::volumioGetQueue
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CoreStateMachine::getQueue
Oct 11 17:56:02 volumio-x86 volumio[3506]: info: CorePlayQueue::getQueue
Oct 11 17:56:04 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%90%A8%E9%A1%B6%E9%A1%B6/%E4%B8%87%E7%89%A9%E7%94%9F/d10cc6a6-580b-4477-a22a-49bb32d77e8c.png'
Oct 11 17:56:04 volumio-x86 sudo[3760]:  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
Oct 11 17:56:04 volumio-x86 sudo[3760]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:04 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:04 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:04 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:04 volumio-x86 sudo[3760]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:04 volumio-x86 volumio[3506]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds
Oct 11 17:56:05 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E5%87%B0%E4%BC%A0%E5%A5%87/%E6%9C%80%E7%82%AB%E6%B0%91%E6%97%8F%E9%A3%8E/2e58e1d2-cd51-439a-8d13-69ffb7c192e0.jpg'
Oct 11 17:56:06 volumio-x86 volumio[3506]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Oct 11 17:56:06 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%B1%B1%E6%AD%8C%E5%AF%A5%E5%93%89/dc2f5966-f5d9-4688-a6a9-60fc6136bae3.jpg'
Oct 11 17:56:06 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%B1%B1%E6%AD%8C%E5%AF%A5%E5%93%89/dc2f5966-f5d9-4688-a6a9-60fc6136bae3.jpg'
Oct 11 17:56:06 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%B1%B1%E6%AD%8C%E5%AF%A5%E5%93%89/dc2f5966-f5d9-4688-a6a9-60fc6136bae3.jpg'
Oct 11 17:56:06 volumio-x86 volumio[3506]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%80%E9%83%8E/%E5%88%80%E9%83%8E%E2%85%A2/90019d15-a03c-4be9-b940-7b75487243e3.jpg'
Oct 11 17:56:07 volumio-x86 sudo[3779]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:56:07 volumio-x86 sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:07 volumio-x86 sudo[3779]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:07 volumio-x86 sudo[3782]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:56:07 volumio-x86 sudo[3782]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:07 volumio-x86 sudo[3782]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:07 volumio-x86 sudo[3785]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Oct 11 17:56:07 volumio-x86 sudo[3785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:07 volumio-x86 sudo[3785]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:07 volumio-x86 volumio[3506]: info: Upmpdcli Daemon Started
Oct 11 17:56:09 volumio-x86 sudo[3826]:  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
Oct 11 17:56:09 volumio-x86 sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:09 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:09 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:09 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:09 volumio-x86 sudo[3826]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:09 volumio-x86 volumio[3506]: info: Cannot mount NAS local at system boot, trial number 3 ,retrying in 5 seconds
Oct 11 17:56:12 volumio-x86 volumio[3506]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:56:12 volumio-x86 volumio[3506]: Error: connect ECONNREFUSED 157.240.21.9:443
Oct 11 17:56:12 volumio-x86 volumio[3506]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Oct 11 17:56:12 volumio-x86 volumio[3506]:   errno: -111,
Oct 11 17:56:12 volumio-x86 volumio[3506]:   code: 'ECONNREFUSED',
Oct 11 17:56:12 volumio-x86 volumio[3506]:   syscall: 'connect',
Oct 11 17:56:12 volumio-x86 volumio[3506]:   address: '157.240.21.9',
Oct 11 17:56:12 volumio-x86 volumio[3506]:   port: 443
Oct 11 17:56:12 volumio-x86 volumio[3506]: }
Oct 11 17:56:12 volumio-x86 volumio[3506]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:56:12 volumio-x86 sudo[3842]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-11 17:55
Oct 11 17:56:12 volumio-x86 sudo[3842]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:12 volumio-x86 sudo[3842]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:12 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:56:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Oct 11 17:56:12 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:56:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Oct 11 17:56:12 volumio-x86 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Oct 11 17:56:12 volumio-x86 systemd[1]: volumio.service: Failed with result 'exit-code'.
Oct 11 17:56:12 volumio-x86 systemd[1]: Started dynamicswap service.
Oct 11 17:56:12 volumio-x86 systemd[1]: dynamicswap.service: Succeeded.
Oct 11 17:56:12 volumio-x86 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Oct 11 17:56:12 volumio-x86 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3.
Oct 11 17:56:13 volumio-x86 systemd[1]: Started dynamicswap service.
Oct 11 17:56:13 volumio-x86 systemd[1]: Stopped Volumio Backend Module.
Oct 11 17:56:13 volumio-x86 systemd[1]: Started Volumio Backend Module.
Oct 11 17:56:13 volumio-x86 systemd[1]: dynamicswap.service: Succeeded.
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -----            Volumio3              ----
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -----          System startup          ----
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: MYVOLUMIO Environment detected
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Plugin folders cleanup
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning into folder /volumio/app/plugins/
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category audio_interface
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category miscellanea
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category music_service
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category plugins.json
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category system_controller
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category user_interface
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning into folder /data/plugins/
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category music_service
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category system_controller
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Scanning category user_interface
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Plugin folders cleanup completed
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -----      Core plugins startup        ----
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Loading plugins from folder /volumio/app/plugins/
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Adding plugin upnp to MyMusic Plugins
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Loading plugins from folder /data/plugins/
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Loading plugin "system"...
Oct 11 17:56:14 volumio-x86 volumio[3854]: info: Loading plugin "appearance"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "network"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Refreshing Cached IP Addresses
Oct 11 17:56:15 volumio-x86 sudo[3880]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:56:15 volumio-x86 sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:15 volumio-x86 sudo[3880]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:15 volumio-x86 sudo[3882]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:56:15 volumio-x86 sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:15 volumio-x86 sudo[3882]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "services"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "alsa_controller"...
Oct 11 17:56:15 volumio-x86 sudo[3894]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 11 17:56:15 volumio-x86 sudo[3894]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "wizard"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "networkfs"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Starting Udev Watcher for removable devices
Oct 11 17:56:15 volumio-x86 sudo[3906]:  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
Oct 11 17:56:15 volumio-x86 sudo[3906]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Ignoring mount for partition: boot
Oct 11 17:56:15 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:15 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:15 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:15 volumio-x86 sudo[3906]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Ignoring mount for partition: d35eb915-f24a-437a-a43b-4afe991304f7
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Ignoring mount for partition: 2fc5abe1-9579-4377-a3f7-b00c1ebaeecd
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "volumio_command_line_client"...
Oct 11 17:56:15 volumio-x86 volumio[3854]: info: Loading plugin "upnp"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: [1791712576000] Starting Upmpd Daemon
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "my_music"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "mpd"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "upnp_browser"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "alarm-clock"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "airplay_emulation"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Starting Shairport Sync
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "last_100"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "webradio"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "i2s_dacs"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: I2S DAC not set, start Auto-detection
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "volumiodiscovery"...
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** For more information see 
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:56:16 volumio-x86 volumio[3854]: *** WARNING *** For more information see 
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** For more information see 
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:56:16 volumio-x86 node[3854]: *** WARNING *** For more information see 
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Discovery: Started advertising with name: Volumio-x86
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Plugin jellyfin is not enabled
Oct 11 17:56:16 volumio-x86 volumio[3854]: info: Loading plugin "autostart"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Applying required configuration parameters for plugin autostart
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: AutoStart - onVolumioStart -  read config.json
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "peppy_screensaver"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Applying required configuration parameters for plugin peppy_screensaver
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "outputs"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "albumart"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Plugin example_plugin is not enabled
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "inputs"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "updater_comm"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Plugin mpdemulation is not enabled
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "rest_api"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading plugin "websocket"...
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Starting Socket.io Server version 2.3.0
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Plugin RoonBridge is not enabled
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Plugin Systeminfo is not enabled
Oct 11 17:56:17 volumio-x86 volumio[3854]: Forking 3 albumart workers
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Loading i18n strings for locale zh
Oct 11 17:56:17 volumio-x86 volumio[3854]: Updating browse sources language
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::initPlayerControls
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:56:17 volumio-x86 volumio[3854]: Express server listening on port 3000
Oct 11 17:56:17 volumio-x86 volumio[3854]:  [Metrics]  WebUI:  4s 62.88ms 
Oct 11 17:56:17 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:56:17] [connect] Successful connection
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Setting Device type: x86
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreStateMachine::resetVolumioState
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreStateMachine::getcurrentVolume
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioRetrievevolume
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:17 volumio-x86 volumio[3854]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Completed loading Core Plugins
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Preparing to generate the ALSA configuration file
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Volumio Network Manager: Network status updated: 2
Oct 11 17:56:17 volumio-x86 volumio[3854]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Reading ALSA contributions from plugins.
Oct 11 17:56:18 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to 192.168.1.103:3000 from 192.168.1.93 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1
Oct 11 17:56:18 volumio-x86 volumio[3854]: 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: 2
Oct 11 17:56:18 volumio-x86 volumio[3854]: 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
Oct 11 17:56:18 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:56:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1791712577 101
Oct 11 17:56:18 volumio-x86 volumio[3854]: 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
Oct 11 17:56:18 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Reloading queue from file
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreStateMachine::setRepeat null single undefined
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreStateMachine::setRandom true
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:18 volumio-x86 volumio[3854]: Starting albumart workers
Oct 11 17:56:18 volumio-x86 volumio[3854]: Starting albumart workers
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:18 volumio-x86 volumio[3854]: Starting albumart workers
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Asound.conf file unchanged, so no further update is needed
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Output device has changed, restarting MPD
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Output device has changed, restarting Shairport Sync
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:18 volumio-x86 sudo[3963]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 11 17:56:18 volumio-x86 sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 sudo[3963]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:18 volumio-x86 sudo[3965]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 17:56:18 volumio-x86 sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 systemd[1]: Stopping Music Player Daemon...
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: ___________ START PLUGINS ___________
Oct 11 17:56:18 volumio-x86 systemd[1]: mpd.service: Succeeded.
Oct 11 17:56:18 volumio-x86 systemd[1]: Stopped Music Player Daemon.
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: ControllerMpd::onStart: Initializing MPD
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Creating MPD Configuration file
Oct 11 17:56:18 volumio-x86 systemd[1]: Starting Music Player Daemon...
Oct 11 17:56:18 volumio-x86 sudo[3973]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 11 17:56:18 volumio-x86 sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: [1791712578631] CoreMusicLibrary::Adding element 媒体服务器
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:18 volumio-x86 sudo[3973]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:18 volumio-x86 sudo[3976]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 17:56:18 volumio-x86 sudo[3976]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:18 volumio-x86 sudo[3971]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 17:56:18 volumio-x86 sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 sudo[3971]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:18 volumio-x86 systemd[1]: mpd.service: Succeeded.
Oct 11 17:56:18 volumio-x86 systemd[1]: Stopped Music Player Daemon.
Oct 11 17:56:18 volumio-x86 systemd[1]: Starting Music Player Daemon...
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:18 volumio-x86 sudo[3983]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 17:56:18 volumio-x86 sudo[3983]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:18 volumio-x86 sudo[3983]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: [1791712578902] CoreMusicLibrary::Adding element Last_100
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: [1791712578907] CoreMusicLibrary::Adding element Webradio
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:18 volumio-x86 volumio[3854]: info: Initializing BBC Radios
Oct 11 17:56:18 volumio-x86 sudo[3894]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: AutoStart - onStart
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Loading i18n strings for locale zh
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: /tmp/myfifo created
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: /tmp/myfifosa created
Oct 11 17:56:19 volumio-x86 sudo[4028]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Oct 11 17:56:19 volumio-x86 sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:19 volumio-x86 sudo[4028]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: snd-dummy loaded
Oct 11 17:56:19 volumio-x86 volumio[3854]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Volumio Calling Home
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Discovery: Found device Volumio-x86
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: MPD Permissions set
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: MPD Permissions set
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:19 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:19 volumio-x86 volumio[3854]: 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
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Starting Shairport Sync
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Starting Shairport Sync
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Starting Shairport Sync
Oct 11 17:56:19 volumio-x86 sudo[4050]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:19 volumio-x86 sudo[4050]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:19 volumio-x86 sudo[4052]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:19 volumio-x86 sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:19 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:19 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:56:19 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:19 volumio-x86 sudo[4055]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:56:19 volumio-x86 sudo[4055]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:56:19 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:19 volumio-x86 sudo[4050]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:19 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:56:19 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 11 17:56:19 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:56:19 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 11 17:56:19 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:56:19 volumio-x86 sudo[4055]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:19 volumio-x86 sudo[4052]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Received Get System Info
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Listing playlists
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Shairport-Sync Started
Oct 11 17:56:19 volumio-x86 volumio[3854]: Error adding Membership: Error: addMembership EINVAL
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Shairport-Sync Started
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Shairport-Sync Started
Oct 11 17:56:19 volumio-x86 volumio[3854]: info: Volumio called home
Oct 11 17:56:20 volumio-x86 mpd[3988]: Oct 11 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 17:56:20 volumio-x86 systemd[1]: Started Music Player Daemon.
Oct 11 17:56:20 volumio-x86 sudo[3965]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[3976]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: Completed starting Core Plugins
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: -----    MyVolumio plugins startup     ----
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: -------------------------------------------
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Oct 11 17:56:20 volumio-x86 volumio[3854]: error: MPD error: The expression evaluated to a falsy value:
Oct 11 17:56:20 volumio-x86 volumio[3854]:   assert.ok(self.idling)
Oct 11 17:56:20 volumio-x86 volumio[3854]: error: The expression evaluated to a falsy value:
Oct 11 17:56:20 volumio-x86 volumio[3854]:   assert.ok(self.idling)
Oct 11 17:56:20 volumio-x86 volumio[3854]: error: updateQueue error: null
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Oct 11 17:56:20 volumio-x86 sudo[4069]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Oct 11 17:56:20 volumio-x86 sudo[4069]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 sudo[4069]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[4075]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:20 volumio-x86 sudo[4075]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 sudo[4075]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[4083]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:20 volumio-x86 sudo[4083]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 sudo[4083]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[4089]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Oct 11 17:56:20 volumio-x86 sudo[4089]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 sudo[4089]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[4094]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:56:20 volumio-x86 sudo[4094]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: MPD running with PID3988
Oct 11 17:56:20 volumio-x86 volumio[3854]:  ,establishing connection
Oct 11 17:56:20 volumio-x86 sudo[4094]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 sudo[4097]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:56:20 volumio-x86 sudo[4097]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:20 volumio-x86 volumio[3854]: error: updateQueue error: null
Oct 11 17:56:20 volumio-x86 sudo[4097]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:20 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:23 volumio-x86 sudo[4107]:  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
Oct 11 17:56:23 volumio-x86 sudo[4107]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:23 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:23 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:23 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:23 volumio-x86 sudo[4107]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:23 volumio-x86 volumio[3854]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds
Oct 11 17:56:25 volumio-x86 volumio[3854]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Oct 11 17:56:26 volumio-x86 sudo[4126]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:56:26 volumio-x86 sudo[4126]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:26 volumio-x86 sudo[4126]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:26 volumio-x86 sudo[4129]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:56:26 volumio-x86 sudo[4129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:26 volumio-x86 sudo[4129]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:26 volumio-x86 sudo[4132]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Oct 11 17:56:26 volumio-x86 sudo[4132]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:26 volumio-x86 sudo[4132]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:26 volumio-x86 volumio[3854]: info: Upmpdcli Daemon Started
Oct 11 17:56:28 volumio-x86 sudo[4135]:  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
Oct 11 17:56:28 volumio-x86 sudo[4135]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:28 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:28 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:28 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:28 volumio-x86 sudo[4135]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:28 volumio-x86 volumio[3854]: info: Cannot mount NAS local at system boot, trial number 3 ,retrying in 5 seconds
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin bluetooth to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin multiroom to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin metavolumio to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin cd_controller to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin smart_inputs to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Adding plugin tidalconnect to MyMusic Plugins
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: Starting MyVolumio Remote Streaming Endpoints
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: MyVolumio login type: Token
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Oct 11 17:56:29 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: Starting Streaming Service Transparent Proxy
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: Streaming services startup
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: Starting Streaming Daemon
Oct 11 17:56:30 volumio-x86 sudo[4147]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 11 17:56:30 volumio-x86 sudo[4147]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:30 volumio-x86 sudo[4147]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:30 volumio-x86 volumio[3854]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Oct 11 17:56:30 volumio-x86 volumio[3854]: error: Cannot start Volumio Streaming Daemon
Oct 11 17:56:30 volumio-x86 volumio[3854]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 11 17:56:30 volumio-x86 volumio[3854]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 11 17:56:31 volumio-x86 volumio[3854]: STREAMING PROXY: Starting server on port 3245
Oct 11 17:56:31 volumio-x86 volumio[3854]: Node JS runtime: 14
Oct 11 17:56:33 volumio-x86 sudo[4157]:  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
Oct 11 17:56:33 volumio-x86 sudo[4157]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:33 volumio-x86 sudo[4157]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:33 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:33 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:33 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:33 volumio-x86 volumio[3854]: info: Cannot mount NAS local at system boot, trial number 4 ,retrying in 5 seconds
Oct 11 17:56:38 volumio-x86 sudo[4176]:  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
Oct 11 17:56:38 volumio-x86 sudo[4176]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:56:38 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:56:38 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:56:38 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:56:38 volumio-x86 sudo[4176]: pam_unix(sudo:session): session closed for user root
Oct 11 17:56:38 volumio-x86 volumio[3854]: info: Cannot mount NAS at system boot, trial number 4 ,stopping
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: AutoStart - Plugin is starting
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetQueue
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::getQueue
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getQueue
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: AutoStart - start playing
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: AutoStart - start playing with no specific position
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPlay
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::play index 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::stop
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::play index undefined
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::startPlaybackTimer
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: [1791712599134] ControllerUPNPBrowser::clearAddPlayTrack
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand stop
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand stop took 13 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand clear
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand clear took 8 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.61:8200/MediaItems/4930.flac"
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: error: updateQueue error: null
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 15ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: error: updateQueue error: null
Oct 11 17:56:39 volumio-x86 volumio[3854]: error: updateQueue error: null
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.61:8200/MediaItems/4930.flac"
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 17ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 14ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand add "http://192.168.1.61:8200/MediaItems/4930.flac" took 3 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::setConsumeUpdateService mpd
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces system playlist update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Ignoring MPD Status Update
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 11ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand play took 9 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 7ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 6ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 15 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 17 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 15 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 2 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus stop
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 33ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info:
Oct 11 17:56:39 volumio-x86 volumio[3854]: ---------------------------- MPD announces state update: player
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::getState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 17 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 16 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 16 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 4 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand status took 3 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseState
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Received an update from plugin. extracting info from payload
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Received an update from plugin. extracting info from payload
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 91ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 91ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 66 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 66 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: sendMpdCommand playlistinfo took 65 milliseconds
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Received an update from plugin. extracting info from payload
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"882 Kbps","isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Received an update from plugin. extracting info from payload
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ControllerMpd::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::servicePushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CorePlayQueue::getTrack 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"882 Kbps","isStreaming":false,"title":"4930.flac","artist":null,"album":null,"uri":"http://192.168.1.61:8200/MediaItems/4930.flac","trackType":"flac"}
Oct 11 17:56:39 volumio-x86 volumio[3854]: verbose: CURRENT POSITION 0
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: Received an update from plugin. extracting info from payload
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreStateMachine::pushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioPushState
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 203ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 191ms
Oct 11 17:56:39 volumio-x86 volumio[3854]: info: ------------------------------ 191ms
Oct 11 17:56:41 volumio-x86 volumio[3854]: 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: 8
Oct 11 17:56:41 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:44 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 11 17:56:44 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Oct 11 17:56:44 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 11 17:56:49 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:56:50 volumio-x86 volumio[3854]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: Completed starting MyVolumio Plugin
Oct 11 17:56:50 volumio-x86 volumio[3854]:  [Metrics]  CommandRouter:  36s 362.56ms 
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumiosetStartupVolume
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: CoreCommandRouter::Close All Modals sent
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: CoreCommandRouter::Close All Modals sent
Oct 11 17:56:50 volumio-x86 volumio[3854]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Oct 11 17:56:50 volumio-x86 volumio[3854]: aplay: main:828: audio open error: Device or resource busy
Oct 11 17:56:51 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Oct 11 17:56:51 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 11 17:56:51 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Oct 11 17:56:57 volumio-x86 volumio[3854]: info: BOOT COMPLETED
Oct 11 17:57:00 volumio-x86 volumio[3854]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
Oct 11 17:57:00 volumio-x86 volumio[3854]: info: Retrying Login Due to Network Error
Oct 11 17:57:01 volumio-x86 volumio-remote-updater[825]: No test mode
Oct 11 17:57:01 volumio-x86 volumio-remote-updater[825]: No alpha test mode
Oct 11 17:57:01 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Oct 11 17:57:02 volumio-x86 volumio[3854]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nFix for BLE notification issues
\nFix for mDNS/BCT
\n
\nNEW ADDITIONS
\n\nUpdate Tidal SDK
\nUpdate Qobuz Connect SDK
\n\n","title":"Update v3.912","updateavailable":true}
Oct 11 17:57:02 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 11 17:57:09 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:10 volumio-x86 volumio[3854]: info: MyVolumio login type: Token
Oct 11 17:57:12 volumio-x86 sudo[4328]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:57:12 volumio-x86 sudo[4328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:12 volumio-x86 sudo[4328]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:12 volumio-x86 sudo[4330]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:57:12 volumio-x86 sudo[4330]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:12 volumio-x86 sudo[4330]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:13 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.44 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.240624.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/149.0.7827.91 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Oct 11 17:57:13 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: Received Get System Info
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: Listing playlists
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 11 17:57:14 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 11 17:57:14 volumio-x86 sudo[4334]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:57:14 volumio-x86 sudo[4334]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:14 volumio-x86 sudo[4334]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:14 volumio-x86 sudo[4337]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:57:14 volumio-x86 sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:14 volumio-x86 sudo[4337]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:14 volumio-x86 volumio[3854]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.44 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.240624.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/149.0.7827.91 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: Received Get System Info
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: Listing playlists
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 11 17:57:15 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: Received Get System Info
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: Discovery: Getting this device information
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:16 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:57:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Oct 11 17:57:17 volumio-x86 volumio[3854]: info: CoreCommandRouter::volumioGetQueue
Oct 11 17:57:17 volumio-x86 volumio[3854]: info: CoreStateMachine::getQueue
Oct 11 17:57:17 volumio-x86 volumio[3854]: info: CorePlayQueue::getQueue
Oct 11 17:57:24 volumio-x86 volumio[3854]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 11 17:57:35 volumio-x86 volumio[3854]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BEYOND/%E9%BB%84%E5%AE%B6%E9%A9%B9%E5%8E%9F%E4%BD%9C%E7%B2%BE%E9%80%89%E9%9B%86/fab35759-41fe-4a0e-9f19-65f612323903.jpg'
Oct 11 17:57:37 volumio-x86 volumio[3854]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:57:37 volumio-x86 volumio[3854]: Error: connect ECONNREFUSED 157.240.21.9:443
Oct 11 17:57:37 volumio-x86 volumio[3854]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Oct 11 17:57:37 volumio-x86 volumio[3854]:   errno: -111,
Oct 11 17:57:37 volumio-x86 volumio[3854]:   code: 'ECONNREFUSED',
Oct 11 17:57:37 volumio-x86 volumio[3854]:   syscall: 'connect',
Oct 11 17:57:37 volumio-x86 volumio[3854]:   address: '157.240.21.9',
Oct 11 17:57:37 volumio-x86 volumio[3854]:   port: 443
Oct 11 17:57:37 volumio-x86 volumio[3854]: }
Oct 11 17:57:37 volumio-x86 volumio[3854]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:57:37 volumio-x86 sudo[4398]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-11 17:56
Oct 11 17:57:37 volumio-x86 sudo[4398]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:37 volumio-x86 sudo[4398]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:37 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:57:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Oct 11 17:57:37 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:57:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Oct 11 17:57:37 volumio-x86 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Oct 11 17:57:37 volumio-x86 systemd[1]: volumio.service: Failed with result 'exit-code'.
Oct 11 17:57:37 volumio-x86 systemd[1]: Started dynamicswap service.
Oct 11 17:57:37 volumio-x86 systemd[1]: dynamicswap.service: Succeeded.
Oct 11 17:57:37 volumio-x86 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Oct 11 17:57:37 volumio-x86 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4.
Oct 11 17:57:37 volumio-x86 systemd[1]: Stopped Volumio Backend Module.
Oct 11 17:57:37 volumio-x86 systemd[1]: Started Volumio Backend Module.
Oct 11 17:57:37 volumio-x86 systemd[1]: Started dynamicswap service.
Oct 11 17:57:37 volumio-x86 systemd[1]: dynamicswap.service: Succeeded.
Oct 11 17:57:38 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:38 volumio-x86 volumio[4410]: info: -----            Volumio3              ----
Oct 11 17:57:38 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:38 volumio-x86 volumio[4410]: info: -----          System startup          ----
Oct 11 17:57:38 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: MYVOLUMIO Environment detected
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Plugin folders cleanup
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning into folder /volumio/app/plugins/
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category audio_interface
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category miscellanea
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category music_service
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category plugins.json
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category system_controller
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category user_interface
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning into folder /data/plugins/
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category music_service
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category system_controller
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Scanning category user_interface
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Plugin folders cleanup completed
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: -----      Core plugins startup        ----
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Loading plugins from folder /volumio/app/plugins/
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Adding plugin upnp to MyMusic Plugins
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Loading plugins from folder /data/plugins/
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Loading plugin "system"...
Oct 11 17:57:39 volumio-x86 volumio[4410]: info: Loading plugin "appearance"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "network"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Refreshing Cached IP Addresses
Oct 11 17:57:40 volumio-x86 sudo[4438]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:57:40 volumio-x86 sudo[4438]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:40 volumio-x86 sudo[4438]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:40 volumio-x86 sudo[4440]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:57:40 volumio-x86 sudo[4440]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:40 volumio-x86 sudo[4440]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "services"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "alsa_controller"...
Oct 11 17:57:40 volumio-x86 sudo[4450]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 11 17:57:40 volumio-x86 sudo[4450]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "wizard"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "networkfs"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Starting Udev Watcher for removable devices
Oct 11 17:57:40 volumio-x86 sudo[4464]:  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
Oct 11 17:57:40 volumio-x86 sudo[4464]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Ignoring mount for partition: boot
Oct 11 17:57:40 volumio-x86 sudo[4464]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:40 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:57:40 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:57:40 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Ignoring mount for partition: d35eb915-f24a-437a-a43b-4afe991304f7
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Ignoring mount for partition: 2fc5abe1-9579-4377-a3f7-b00c1ebaeecd
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "volumio_command_line_client"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "upnp"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: [1791712660765] Starting Upmpd Daemon
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "my_music"...
Oct 11 17:57:40 volumio-x86 volumio[4410]: info: Loading plugin "mpd"...
Oct 11 17:57:40 volumio-x86 nmbd[1043]: [2026/10/11 17:57:40.814733,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Oct 11 17:57:40 volumio-x86 nmbd[1043]:   query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.103 for name WORKGROUP<1d>.
Oct 11 17:57:40 volumio-x86 nmbd[1043]:   This response was from IP 192.168.1.61, reporting an IP address of 192.168.1.61.
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "upnp_browser"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "alarm-clock"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "airplay_emulation"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Starting Shairport Sync
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "last_100"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "webradio"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "i2s_dacs"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: I2S DAC not set, start Auto-detection
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "volumiodiscovery"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** For more information see 
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:57:41 volumio-x86 volumio[4410]: *** WARNING *** For more information see 
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** For more information see 
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 11 17:57:41 volumio-x86 node[4410]: *** WARNING *** For more information see 
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Discovery: Started advertising with name: Volumio-x86
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Plugin jellyfin is not enabled
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "autostart"...
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Applying required configuration parameters for plugin autostart
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: AutoStart - onVolumioStart -  read config.json
Oct 11 17:57:41 volumio-x86 volumio[4410]: info: Loading plugin "peppy_screensaver"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Applying required configuration parameters for plugin peppy_screensaver
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "outputs"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "albumart"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Plugin example_plugin is not enabled
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "inputs"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "updater_comm"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Plugin mpdemulation is not enabled
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "rest_api"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading plugin "websocket"...
Oct 11 17:57:42 volumio-x86 volumio[4410]: Forking 3 albumart workers
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Starting Socket.io Server version 2.3.0
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Plugin RoonBridge is not enabled
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Plugin Systeminfo is not enabled
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Loading i18n strings for locale zh
Oct 11 17:57:42 volumio-x86 volumio[4410]: Updating browse sources language
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:42 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:57:42] [connect] Successful connection
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::initPlayerControls
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: Express server listening on port 3000
Oct 11 17:57:42 volumio-x86 volumio[4410]:  [Metrics]  WebUI:  4s 117.97ms 
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Setting Device type: x86
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreStateMachine::resetVolumioState
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreStateMachine::getcurrentVolume
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioRetrievevolume
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:42 volumio-x86 volumio[4410]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Completed loading Core Plugins
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Preparing to generate the ALSA configuration file
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Volumio Network Manager: Network status updated: 2
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Oct 11 17:57:42 volumio-x86 volumio[4410]: info: Reading ALSA contributions from plugins.
Oct 11 17:57:42 volumio-x86 volumio[4410]: 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
Oct 11 17:57:42 volumio-x86 volumio[4410]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.44 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.240624.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/149.0.7827.91 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Oct 11 17:57:42 volumio-x86 volumio[4410]: 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: 3
Oct 11 17:57:42 volumio-x86 volumio[4410]: 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
Oct 11 17:57:42 volumio-x86 volumio[4410]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.44 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.240624.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/149.0.7827.91 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Oct 11 17:57:42 volumio-x86 volumio[4410]: 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
Oct 11 17:57:42 volumio-x86 volumio-remote-updater[825]: [2026-10-11 17:57:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1791712662 101
Oct 11 17:57:42 volumio-x86 volumio[4410]: 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: 7
Oct 11 17:57:42 volumio-x86 volumio[4410]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Oct 11 17:57:43 volumio-x86 volumio[4410]: Starting albumart workers
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Reloading queue from file
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreStateMachine::setRepeat null single undefined
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreStateMachine::setRandom true
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:43 volumio-x86 volumio[4410]: Starting albumart workers
Oct 11 17:57:43 volumio-x86 volumio[4410]: Starting albumart workers
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Asound.conf file unchanged, so no further update is needed
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Output device has changed, restarting MPD
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Output device has changed, restarting Shairport Sync
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:43 volumio-x86 sudo[4536]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 11 17:57:43 volumio-x86 sudo[4536]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:43 volumio-x86 sudo[4536]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:43 volumio-x86 sudo[4538]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 17:57:43 volumio-x86 sudo[4538]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: ___________ START PLUGINS ___________
Oct 11 17:57:43 volumio-x86 systemd[1]: Stopping Music Player Daemon...
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: ControllerMpd::onStart: Initializing MPD
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Creating MPD Configuration file
Oct 11 17:57:43 volumio-x86 systemd[1]: mpd.service: Succeeded.
Oct 11 17:57:43 volumio-x86 systemd[1]: Stopped Music Player Daemon.
Oct 11 17:57:43 volumio-x86 sudo[4546]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 11 17:57:43 volumio-x86 sudo[4546]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:43 volumio-x86 systemd[1]: Starting Music Player Daemon...
Oct 11 17:57:43 volumio-x86 sudo[4546]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: [1791712663519] CoreMusicLibrary::Adding element 媒体服务器
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:43 volumio-x86 sudo[4550]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 11 17:57:43 volumio-x86 sudo[4550]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:43 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Oct 11 17:57:43 volumio-x86 systemd[1]: mpd.service: Succeeded.
Oct 11 17:57:43 volumio-x86 systemd[1]: Stopped Music Player Daemon.
Oct 11 17:57:43 volumio-x86 systemd[1]: Starting Music Player Daemon...
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:57:43 volumio-x86 sudo[4554]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Oct 11 17:57:43 volumio-x86 sudo[4554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:43 volumio-x86 sudo[4554]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:43 volumio-x86 sudo[4450]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: [1791712663830] CoreMusicLibrary::Adding element Last_100
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: [1791712663834] CoreMusicLibrary::Adding element Webradio
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Initializing BBC Radios
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: AutoStart - onStart
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: Loading i18n strings for locale zh
Oct 11 17:57:43 volumio-x86 volumio[4410]: info: /tmp/myfifo created
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: /tmp/myfifosa created
Oct 11 17:57:44 volumio-x86 sudo[4602]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
Oct 11 17:57:44 volumio-x86 sudo[4602]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:44 volumio-x86 sudo[4602]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: snd-dummy loaded
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Volumio Calling Home
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Discovery: Found device Volumio-x86
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: MPD Permissions set
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: MPD Permissions set
Oct 11 17:57:44 volumio-x86 volumio[4410]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Starting Shairport Sync
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Starting Shairport Sync
Oct 11 17:57:44 volumio-x86 sudo[4626]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:57:44 volumio-x86 sudo[4626]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Starting Shairport Sync
Oct 11 17:57:44 volumio-x86 sudo[4628]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:57:44 volumio-x86 sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:44 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:57:44 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:57:44 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:57:44 volumio-x86 sudo[4632]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 11 17:57:44 volumio-x86 sudo[4632]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:44 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:57:44 volumio-x86 sudo[4626]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 11 17:57:44 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 11 17:57:44 volumio-x86 systemd[1]: shairport-sync.service: Succeeded.
Oct 11 17:57:44 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Shairport-Sync Started
Oct 11 17:57:44 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 11 17:57:44 volumio-x86 sudo[4628]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 volumio[4410]: Error adding Membership: Error: addMembership EINVAL
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Volumio called home
Oct 11 17:57:44 volumio-x86 sudo[4632]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Shairport-Sync Started
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Shairport-Sync Started
Oct 11 17:57:44 volumio-x86 mpd[4567]: Oct 11 17:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 11 17:57:44 volumio-x86 systemd[1]: Started Music Player Daemon.
Oct 11 17:57:44 volumio-x86 sudo[4550]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 sudo[4538]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: Completed starting Core Plugins
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: -----    MyVolumio plugins startup     ----
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: -------------------------------------------
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 11 17:57:44 volumio-x86 volumio[4410]: info: MPD running with PID4567
Oct 11 17:57:44 volumio-x86 volumio[4410]:  ,establishing connection
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: MPD error: The expression evaluated to a falsy value:
Oct 11 17:57:44 volumio-x86 volumio[4410]:   assert.ok(self.idling)
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: The expression evaluated to a falsy value:
Oct 11 17:57:44 volumio-x86 volumio[4410]:   assert.ok(self.idling)
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: MPD error: The expression evaluated to a falsy value:
Oct 11 17:57:44 volumio-x86 volumio[4410]:   assert.ok(self.idling)
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: The expression evaluated to a falsy value:
Oct 11 17:57:44 volumio-x86 volumio[4410]:   assert.ok(self.idling)
Oct 11 17:57:44 volumio-x86 volumio[4410]: error: updateQueue error: null
Oct 11 17:57:45 volumio-x86 volumio[4410]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.44 UA: Mozilla/5.0 (Linux; Android 14; 2109119DG Build/UKQ1.240624.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/149.0.7827.91 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: Received Get System Info
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: Discovery: Getting this device information
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: Listing playlists
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetQueue
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CoreStateMachine::getQueue
Oct 11 17:57:45 volumio-x86 volumio[4410]: info: CorePlayQueue::getQueue
Oct 11 17:57:45 volumio-x86 volumio[4410]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%88%98%E5%BE%B7%E5%8D%8E/3b6f1fcf-2e63-4486-b3d3-ac9ba65b5057.jpg'
Oct 11 17:57:45 volumio-x86 volumio[4410]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%8A%89%E5%BE%B7%E8%8F%AF/%E4%BE%86%E7%94%9F%E7%B7%A3/5b31fb42-7a02-4f40-9d6b-607fd0d14d45.jpg'
Oct 11 17:57:46 volumio-x86 volumio[4410]: 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: 11
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: Received Get System Info
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: Discovery: Getting this device information
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioGetState
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 0
Oct 11 17:57:46 volumio-x86 volumio[4410]: info: Listing playlists
Oct 11 17:57:47 volumio-x86 sudo[4647]:  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
Oct 11 17:57:47 volumio-x86 sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:47 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics
Oct 11 17:57:47 volumio-x86 kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Oct 11 17:57:47 volumio-x86 kernel: CIFS: VFS: cifs_mount failed w/return code = -111
Oct 11 17:57:47 volumio-x86 sudo[4647]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:47 volumio-x86 volumio[4410]: info: Cannot mount NAS local at system boot, trial number 2 ,retrying in 5 seconds
Oct 11 17:57:49 volumio-x86 volumio[4410]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPlay
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::play index 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::stop
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::play index undefined
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::startPlaybackTimer
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: [1791712670420] ControllerUPNPBrowser::clearAddPlayTrack
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand stop
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand stop took 2 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand clear
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand clear took 2 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.61:8200/MediaItems/4955.flac"
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: error: updateQueue error: null
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 7ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: error: updateQueue error: null
Oct 11 17:57:50 volumio-x86 volumio[4410]: error: updateQueue error: null
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.61:8200/MediaItems/4955.flac"
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 15ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 14ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand add "http://192.168.1.61:8200/MediaItems/4955.flac" took 3 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::setConsumeUpdateService mpd
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 7ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand play took 5 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 4ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 3ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 59 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 65 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 64 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 13ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 9 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 6 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus stop
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 84ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces system playlist update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Ignoring MPD Status Update
Oct 11 17:57:50 volumio-x86 volumio[4410]: info:
Oct 11 17:57:50 volumio-x86 volumio[4410]: ---------------------------- MPD announces state update: player
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::getState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand status
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 20 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 17 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 16 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 7ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 6 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 5ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand status took 4 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseState
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"352 Kbps","isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Received an update from plugin. extracting info from payload
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"355 Kbps","isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Received an update from plugin. extracting info from payload
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Received an update from plugin. extracting info from payload
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 196ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 195ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 143ms
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 107 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: sendMpdCommand playlistinfo took 108 milliseconds
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: ControllerMpd::parseTrackInfo
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Received an update from plugin. extracting info from payload
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ControllerMpd::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::servicePushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CorePlayQueue::getTrack 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"484 Kbps","isStreaming":false,"title":"冰糖葫芦","artist":"冯晓泉","album":null,"uri":"http://192.168.1.61:8200/MediaItems/4955.flac","trackType":"flac"}
Oct 11 17:57:50 volumio-x86 volumio[4410]: verbose: CURRENT POSITION 14
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   stateService play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::syncState   currentStatus play
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: Received an update from plugin. extracting info from payload
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreStateMachine::pushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: CoreCommandRouter::volumioPushState
Oct 11 17:57:50 volumio-x86 volumio[4410]: info: ------------------------------ 183ms
Oct 11 17:57:50 volumio-x86 sudo[4700]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 11 17:57:50 volumio-x86 sudo[4700]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:50 volumio-x86 sudo[4700]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:50 volumio-x86 sudo[4705]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 11 17:57:50 volumio-x86 sudo[4705]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 11 17:57:50 volumio-x86 sudo[4705]: pam_unix(sudo:session): session closed for user root
Oct 11 17:57:50 volumio-x86 volumio[4410]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:57:50 volumio-x86 volumio[4410]: Error: connect ECONNREFUSED 108.160.161.20:443
Oct 11 17:57:50 volumio-x86 volumio[4410]:     at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Oct 11 17:57:50 volumio-x86 volumio[4410]:   errno: -111,
Oct 11 17:57:50 volumio-x86 volumio[4410]:   code: 'ECONNREFUSED',
Oct 11 17:57:50 volumio-x86 volumio[4410]:   syscall: 'connect',
Oct 11 17:57:50 volumio-x86 volumio[4410]:   address: '108.160.161.20',
Oct 11 17:57:50 volumio-x86 volumio[4410]:   port: 443
Oct 11 17:57:50 volumio-x86 volumio[4410]: }
Oct 11 17:57:50 volumio-x86 volumio[4410]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 11 17:57:51 volumio-x86 sudo[4726]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-11 17:56
Oct 11 17:57:51 volumio-x86 sudo[4726]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET"
VOLUMIO_VERSION="3.779"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"