-- Logs begin at Wed 2025-04-30 21:18:39 CST, end at Wed 2025-04-30 21:20:27 CST. -- Apr 30 21:19:00 volumio-x86 volumio[884]: Forking 1 albumart workers Apr 30 21:19:00 volumio-x86 volumio[884]: info: Plugin mpdemulation is not enabled Apr 30 21:19:00 volumio-x86 volumio[884]: info: Loading plugin "rest_api"... Apr 30 21:19:00 volumio-x86 volumio[884]: info: Loading plugin "websocket"... Apr 30 21:19:00 volumio-x86 volumio[884]: info: Starting Socket.io Server version 2.3.0 Apr 30 21:19:00 volumio-x86 volumio[884]: info: Loading plugin "RoonBridge"... Apr 30 21:19:00 volumio-x86 volumio[884]: info: Applying required configuration parameters for plugin RoonBridge Apr 30 21:19:00 volumio-x86 volumio[884]: info: Loading plugin "Systeminfo"... Apr 30 21:19:00 volumio-x86 volumio[884]: Starting albumart workers Apr 30 21:19:01 volumio-x86 volumio[884]: info: Loading i18n strings for locale zh Apr 30 21:19:01 volumio-x86 volumio[884]: Updating browse sources language Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::initPlayerControls Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: Express server listening on port 3000 Apr 30 21:19:01 volumio-x86 volumio[884]: [Metrics] WebUI: 12s 691.46ms Apr 30 21:19:01 volumio-x86 volumio[884]: info: Setting Device type: x86 Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::resetVolumioState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::getcurrentVolume Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioRetrievevolume Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:01 volumio-x86 volumio[884]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 30 21:19:01 volumio-x86 volumio[884]: info: Completed loading Core Plugins Apr 30 21:19:01 volumio-x86 volumio[884]: info: Preparing to generate the ALSA configuration file Apr 30 21:19:01 volumio-x86 volumio[884]: info: Volumio Network Manager: Network status updated: 1 Apr 30 21:19:01 volumio-x86 volumio[884]: info: Cannot mount NAS local at system boot, trial number 1 ,retrying in 5 seconds Apr 30 21:19:01 volumio-x86 volumio[884]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 30 21:19:01 volumio-x86 volumio[884]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 30 21:19:01 volumio-x86 volumio[884]: info: Reading ALSA contributions from plugins. Apr 30 21:19:01 volumio-x86 volumio[884]: verbose: New Socket.io Connection to 192.168.3.68 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Apr 30 21:19:01 volumio-x86 volumio[884]: verbose: New Socket.io Connection to 192.168.3.68 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 30 21:19:01 volumio-x86 volumio[884]: verbose: New Socket.io Connection to 192.168.3.68 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Apr 30 21:19:01 volumio-x86 volumio[884]: verbose: New Socket.io Connection to 192.168.3.68 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:01 volumio-x86 volumio[884]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 30 21:19:01 volumio-x86 volumio[884]: info: Reloading queue from file Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::setRepeat null single undefined Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::setRandom null Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:01 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:01 volumio-x86 volumio[884]: info: Asound.conf file unchanged, so no further update is needed Apr 30 21:19:01 volumio-x86 volumio[884]: info: Output device has changed, restarting MPD Apr 30 21:19:01 volumio-x86 sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 21:19:01 volumio-x86 sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 sudo[1377]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:02 volumio-x86 volumio[884]: info: Output device has changed, restarting Shairport Sync Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 21:19:02 volumio-x86 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 systemd[1]: Stopping Music Player Daemon... Apr 30 21:19:02 volumio-x86 volumio[884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 21:19:02 volumio-x86 volumio[884]: info: ___________ START PLUGINS ___________ Apr 30 21:19:02 volumio-x86 volumio[884]: info: ControllerMpd::onStart: Initializing MPD Apr 30 21:19:02 volumio-x86 volumio[884]: info: Creating MPD Configuration file Apr 30 21:19:02 volumio-x86 systemd[1]: mpd.service: Succeeded. Apr 30 21:19:02 volumio-x86 systemd[1]: Stopped Music Player Daemon. Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 21:19:02 volumio-x86 volumio[884]: info: [1746019142131] CoreMusicLibrary::Adding element 媒体服务器 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:02 volumio-x86 sudo[1386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 21:19:02 volumio-x86 sudo[1386]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 systemd[1]: Starting Music Player Daemon... Apr 30 21:19:02 volumio-x86 sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 21:19:02 volumio-x86 sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 sudo[1386]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 30 21:19:02 volumio-x86 systemd[1]: mpd.service: Succeeded. Apr 30 21:19:02 volumio-x86 systemd[1]: Stopped Music Player Daemon. Apr 30 21:19:02 volumio-x86 systemd[1]: Starting Music Player Daemon... Apr 30 21:19:02 volumio-x86 volumio[884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 sudo[1393]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 21:19:02 volumio-x86 sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 sudo[1393]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:02 volumio-x86 volumio[884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 21:19:02 volumio-x86 volumio[884]: info: [1746019142296] CoreMusicLibrary::Adding element Last_100 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 21:19:02 volumio-x86 volumio[884]: info: [1746019142299] CoreMusicLibrary::Adding element Webradio Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 21:19:02 volumio-x86 volumio[884]: info: Initializing BBC Radios Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 21:19:02 volumio-x86 volumio[884]: info: Discovery: Getting this device information Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:02 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 30 21:19:02 volumio-x86 volumio[884]: info: [1746019142418] CoreMusicLibrary::Adding element Jellyfin Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:02 volumio-x86 volumio[884]: Cannot find translation for source Jellyfin Apr 30 21:19:02 volumio-x86 volumio[884]: info: [jellyfin] Initialized plugin with device info: {"id":"c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a","host":"http://192.168.3.68","name":"Volumio-x86","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"","track":"","albumart":"http://192.168.3.68/albumart"}} Apr 30 21:19:02 volumio-x86 volumio[884]: info: AutoStart - onStart Apr 30 21:19:02 volumio-x86 volumio[884]: info: Loading i18n strings for locale zh Apr 30 21:19:02 volumio-x86 volumio[884]: info: /tmp/myfifo created Apr 30 21:19:02 volumio-x86 volumio[884]: info: /tmp/myfifosa created Apr 30 21:19:02 volumio-x86 sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Apr 30 21:19:02 volumio-x86 sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 sudo[1416]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:02 volumio-x86 volumio[884]: info: snd-dummy loaded Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:02 volumio-x86 volumio[884]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 30 21:19:02 volumio-x86 volumio[884]: info: Volumio Calling Home Apr 30 21:19:02 volumio-x86 sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Apr 30 21:19:02 volumio-x86 sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:02 volumio-x86 systemd[1]: Started RoonBridge. Apr 30 21:19:02 volumio-x86 sudo[1431]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:02 volumio-x86 volumio[884]: info: Discovery: adding c9162a36-5b6c-45cd-a4c6-ef7b8f43fa3a Apr 30 21:19:02 volumio-x86 volumio[884]: info: Discovery: Found device Volumio-x86 Apr 30 21:19:02 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:02 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:03 volumio-x86 volumio[884]: info: MPD Permissions set Apr 30 21:19:03 volumio-x86 volumio[884]: info: MPD Permissions set Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:03 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:03 volumio-x86 volumio[884]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 30 21:19:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:03 volumio-x86 sudo[1463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Apr 30 21:19:03 volumio-x86 sudo[1463]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:03 volumio-x86 volumio[884]: info: Starting Shairport Sync Apr 30 21:19:03 volumio-x86 sudo[1463]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:03 volumio-x86 volumio[884]: info: Starting Shairport Sync Apr 30 21:19:03 volumio-x86 sudo[1466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 21:19:03 volumio-x86 sudo[1466]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:03 volumio-x86 volumio[884]: info: Starting Shairport Sync Apr 30 21:19:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Apr 30 21:19:04 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 21:19:04 volumio-x86 sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:04 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 volumio[884]: info: Starting Shairport Sync Apr 30 21:19:04 volumio-x86 sudo[1466]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Apr 30 21:19:04 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 sudo[1468]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 21:19:04 volumio-x86 sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 21:19:04 volumio-x86 sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 30 21:19:04 volumio-x86 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Apr 30 21:19:04 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 sudo[1473]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 30 21:19:04 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Apr 30 21:19:04 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:04 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:04 volumio-x86 sudo[1477]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 RoonBridge[1435]: 00:00:00.021 Warn: get lock file path: /tmp/.rnbgem0- Apr 30 21:19:04 volumio-x86 volumio[884]: info: Shairport-Sync Started Apr 30 21:19:04 volumio-x86 volumio[884]: Error adding Membership: Error: addMembership EINVAL Apr 30 21:19:04 volumio-x86 volumio[884]: info: Shairport-Sync Started Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Apr 30 21:19:04 volumio-x86 mpd[1400]: Apr 30 21:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 30 21:19:04 volumio-x86 systemd[1]: Started Music Player Daemon. Apr 30 21:19:04 volumio-x86 sudo[1380]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 30 21:19:04 volumio-x86 sudo[1388]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:04 volumio-x86 sudo[1485]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 volumio[884]: info: MPD Permissions set Apr 30 21:19:04 volumio-x86 volumio[884]: info: Completed starting Core Plugins Apr 30 21:19:04 volumio-x86 volumio[884]: info: ------------------------------------------- Apr 30 21:19:04 volumio-x86 volumio[884]: info: ----- MyVolumio plugins startup ---- Apr 30 21:19:04 volumio-x86 volumio[884]: info: ------------------------------------------- Apr 30 21:19:04 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 30 21:19:05 volumio-x86 sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 30 21:19:05 volumio-x86 sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:05 volumio-x86 sudo[1506]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:05 volumio-x86 sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 30 21:19:05 volumio-x86 sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:00.847 Trace: [childprocess] using unix child process Apr 30 21:19:05 volumio-x86 volumio-time-update[589]: volumio-time-update-util: Setting system time to: 2025-04-30 21:19:04 Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 30 21:19:05 volumio-x86 sudo[1516]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-30 21:19:04 Apr 30 21:19:05 volumio-x86 sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 sudo[1509]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:05 volumio-x86 dbus-daemon[583]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=1520 comm="timedatectl set-time 2025-04-30 21:19:04 ") Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:00.995 Debug: PathForResource, filename: ../.update Apr 30 21:19:05 volumio-x86 systemd[1]: Starting Time & Date Service... Apr 30 21:19:05 volumio-x86 volumio[884]: error: MPD error: The expression evaluated to a falsy value: Apr 30 21:19:05 volumio-x86 volumio[884]: assert.ok(self.idling) Apr 30 21:19:05 volumio-x86 volumio[884]: error: The expression evaluated to a falsy value: Apr 30 21:19:05 volumio-x86 volumio[884]: assert.ok(self.idling) Apr 30 21:19:05 volumio-x86 volumio[884]: info: MPD running with PID1400 Apr 30 21:19:05 volumio-x86 volumio[884]: ,establishing connection Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 30 21:19:05 volumio-x86 volumio[884]: error: MPD error: The expression evaluated to a falsy value: Apr 30 21:19:05 volumio-x86 volumio[884]: assert.ok(self.idling) Apr 30 21:19:05 volumio-x86 volumio[884]: error: The expression evaluated to a falsy value: Apr 30 21:19:05 volumio-x86 volumio[884]: assert.ok(self.idling) Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.115 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Apr 30 21:19:05 volumio-x86 sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 30 21:19:05 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:19:05 volumio-x86 sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.136 Debug: PathForResource, filename: RAATServer Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.142 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.143 Debug: PathForResource, filename: RoonBridgeHelper Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.143 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: Initializing Apr 30 21:19:05 volumio-x86 systemd[1]: Stopping Music Player Daemon... Apr 30 21:19:05 volumio-x86 volumio[884]: verbose: New Socket.io Connection to 192.168.3.68 from 192.168.3.74 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Edg/135.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Apr 30 21:19:05 volumio-x86 systemd[1]: mpd.service: Succeeded. Apr 30 21:19:05 volumio-x86 systemd[1]: Stopped Music Player Daemon. Apr 30 21:19:05 volumio-x86 systemd[1]: Starting Music Player Daemon... Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: 00:00:01.273 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Apr 30 21:19:05 volumio-x86 sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 30 21:19:05 volumio-x86 sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 dbus-daemon[583]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 30 21:19:05 volumio-x86 systemd[1]: Started Time & Date Service. Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 21:19:05 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:04 volumio-x86 systemd-timedated[1523]: Changed local time to Wed Apr 30 21:19:04 2025 Apr 30 21:19:04 volumio-x86 sudo[1516]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:04 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:04 volumio-x86 volumio-time-update[589]: volumio-time-update-util: System time updated successfully. Apr 30 21:19:04 volumio-x86 systemd[1]: Started UPnP Renderer front-end to MPD. Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:04 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:04 volumio-x86 systemd[1]: Started Volumio Time Update Utility. Apr 30 21:19:04 volumio-x86 systemd[1]: Reached target Multi-User System. Apr 30 21:19:04 volumio-x86 systemd[1]: Reached target Graphical Interface. Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 30 21:19:04 volumio-x86 RoonBridge[1435]: 00:00:01.449 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Apr 30 21:19:04 volumio-x86 sudo[1532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 30 21:19:04 volumio-x86 sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 30 21:19:04 volumio-x86 systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 30 21:19:04 volumio-x86 sudo[1532]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 sudo[1531]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:04 volumio-x86 volumio[884]: info: Received Get System Info Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 21:19:04 volumio-x86 volumio[884]: info: Discovery: Getting this device information Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:04 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 21:19:04 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:04 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:04 volumio-x86 volumio[884]: info: Listing playlists Apr 30 21:19:04 volumio-x86 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 30 21:19:04 volumio-x86 systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 30 21:19:04 volumio-x86 volumio[884]: info: Upmpdcli Daemon Started Apr 30 21:19:04 volumio-x86 RoonBridge[1435]: Not Running (.o) Apr 30 21:19:05 volumio-x86 sudo[1549]: 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 Apr 30 21:19:05 volumio-x86 sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:05 volumio-x86 kernel: CIFS: Attempting to mount //127.0.0.1/Internal Storage/musics Apr 30 21:19:05 volumio-x86 RoonBridge[1435]: Running Apr 30 21:19:05 volumio-x86 sudo[1549]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:06 volumio-x86 mpd[1538]: Apr 30 21:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 30 21:19:06 volumio-x86 systemd[1]: Started Music Player Daemon. Apr 30 21:19:06 volumio-x86 systemd[1]: Startup finished in 23.193s (kernel) + 29.645s (userspace) = 52.839s. Apr 30 21:19:06 volumio-x86 volumio[884]: Upnp client error: Error: This socket has been ended by the other party Apr 30 21:19:06 volumio-x86 sudo[1525]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:06 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:19:06 volumio-x86 RoonBridge[1435]: 00:00:00.068 Warn: get lock file path: /tmp/.rnbhgem0- Apr 30 21:19:07 volumio-x86 volumio[884]: 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 Apr 30 21:19:08 volumio-x86 volumio[1534]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 30 21:19:08 volumio-x86 volumio[884]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 30 21:19:10 volumio-x86 volumio[1534]: ..............................................++++ Apr 30 21:19:10 volumio-x86 volumio[1534]: .......++++ Apr 30 21:19:10 volumio-x86 volumio[1534]: e is 65537 (0x010001) Apr 30 21:19:10 volumio-x86 volumio[1534]: writing RSA key Apr 30 21:19:10 volumio-x86 systemd[1]: systemd-fsckd.service: Succeeded. Apr 30 21:19:10 volumio-x86 volumio-remote-updater[597]: [2025-04-30 21:19:10] [connect] Successful connection Apr 30 21:19:10 volumio-x86 volumio-remote-updater[597]: [2025-04-30 21:19:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746019150 101 Apr 30 21:19:10 volumio-x86 volumio[884]: 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: 9 Apr 30 21:19:11 volumio-x86 volumio[884]: 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: 10 Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetVisibleSources Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:13 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 30 21:19:13 volumio-x86 volumio[884]: info: Received Get System Info Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 21:19:13 volumio-x86 volumio[884]: info: Discovery: Getting this device information Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:13 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:13 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:13 volumio-x86 volumio[884]: info: Listing playlists Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 30 21:19:13 volumio-x86 volumio[884]: info: Received Get System Info Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 30 21:19:13 volumio-x86 volumio[884]: info: Discovery: Getting this device information Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:13 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 30 21:19:13 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 30 21:19:15 volumio-x86 volumio[884]: info: Volumio called home Apr 30 21:19:15 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 30 21:19:20 volumio-x86 volumio[884]: info: AutoStart - Plugin is starting Apr 30 21:19:20 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:19:20 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:19:20 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:19:21 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:21 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:21 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 30 21:19:23 volumio-x86 volumio[884]: error: MyVolumio Plugin failed to start in a timely fashion Apr 30 21:19:23 volumio-x86 volumio[884]: [Metrics] CommandRouter: 34s 960.81ms Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::volumiosetStartupVolume Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::Close All Modals sent Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::Close All Modals sent Apr 30 21:19:23 volumio-x86 volumio[884]: info: Clearing queue after UPNP request Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreStateMachine::ClearQueue Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreStateMachine::stop Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 21:19:23 volumio-x86 volumio[884]: info: CorePlayQueue::clearPlayQueue Apr 30 21:19:23 volumio-x86 volumio[884]: info: CorePlayQueue::saveQueue Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushQueue Apr 30 21:19:23 volumio-x86 volumio[884]: info: Apr 30 21:19:23 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:23 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:23 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:19:23 volumio-x86 volumio[884]: info: ------------------------------ 4ms Apr 30 21:19:23 volumio-x86 volumio[884]: info: Starting UPNP Playback Apr 30 21:19:23 volumio-x86 volumio[884]: info: Preparing playback through UPNP Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:23 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:23 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 3ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:24 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:19:24 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:19:24 volumio-x86 volumio[884]: info: Apr 30 21:19:24 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:19:24 volumio-x86 volumio[884]: info: sendMpdCommand status took 65 milliseconds Apr 30 21:19:24 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:19:24 volumio-x86 volumio[884]: info: sendMpdCommand status took 24 milliseconds Apr 30 21:19:24 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:19:24 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"435 Kbps","isStreaming":false,"title":"好薇 - 阿瓦人民唱新歌","artist":"好薇","album":"Unknown Album","uri":"http://192.168.3.188:35793/bridge-1.flac","trackType":"flac"} Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus stop Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 109ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:19:24 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"672 Kbps","isStreaming":false,"title":"好薇 - 阿瓦人民唱新歌","artist":"好薇","album":"Unknown Album","uri":"http://192.168.3.188:35793/bridge-1.flac","trackType":"flac"} Apr 30 21:19:24 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:19:24 volumio-x86 volumio[884]: info: Received an update from plugin. extracting info from payload Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:24 volumio-x86 volumio[884]: info: ------------------------------ 94ms Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:19:24 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:19:24 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:19:24 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:19:26 volumio-x86 systemd[1]: systemd-hostnamed.service: Succeeded. Apr 30 21:19:30 volumio-x86 volumio[884]: info: BOOT COMPLETED Apr 30 21:19:31 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 21:19:31 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:31 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 30 21:19:34 volumio-x86 systemd[1]: systemd-timedated.service: Succeeded. Apr 30 21:19:42 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:42 volumio-x86 sudo[2053]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Apr 30 21:19:42 volumio-x86 sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:44 volumio-x86 volumio[1534]: 100 Apr 30 21:19:44 volumio-x86 volumio[1534]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 30 21:19:44 volumio-x86 volumio[1534]: Dload Upload Total Spent Left Speed Apr 30 21:19:44 volumio-x86 volumio[884]: info: Apr 30 21:19:44 volumio-x86 volumio[884]: ---------------------------- Client requests Volume 100 Apr 30 21:19:44 volumio-x86 volumio[884]: info: VolumeController::SetAlsaVolume100 Apr 30 21:19:44 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:44 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:45 volumio-x86 volumio[1534]: [158B blob data] Apr 30 21:19:45 volumio-x86 volumio[1534]: {"time":1746019184979,"response":"volume Success"}100 Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: Apr 30 21:19:45 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:19:45 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:19:45 volumio-x86 volumio[884]: info: sendMpdCommand status took 1 milliseconds Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:19:45 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:19:45 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:45 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"好薇 - 阿瓦人民唱新歌","artist":"好薇","album":"Unknown Album","uri":"http://192.168.3.188:35793/bridge-1.flac","trackType":"flac"} Apr 30 21:19:45 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService stop Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::stPlaybackTimer Apr 30 21:19:45 volumio-x86 volumio[884]: info: ------------------------------ 46ms Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:19:45 volumio-x86 volumio[884]: info: Clearing queue after UPNP request Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin bluetooth to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin multiroom to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin metavolumio to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin cd_controller to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 30 21:19:45 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 30 21:19:46 volumio-x86 screensaver.sh[587]: libpng warning: iCCP: known incorrect sRGB profile Apr 30 21:19:46 volumio-x86 screensaver.sh[587]: libpng warning: iCCP: known incorrect sRGB profile Apr 30 21:19:46 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 30 21:19:46 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 30 21:19:46 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:46 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 30 21:19:46 volumio-x86 volumio[884]: info: Starting MyVolumio Remote Streaming Endpoints Apr 30 21:19:46 volumio-x86 volumio[884]: info: MyVolumio login type: Token Apr 30 21:19:47 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 30 21:19:47 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 30 21:19:48 volumio-x86 volumio[884]: info: Starting Streaming Service Transparent Proxy Apr 30 21:19:48 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 30 21:19:48 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 30 21:19:48 volumio-x86 volumio[884]: info: Streaming services startup Apr 30 21:19:48 volumio-x86 volumio[884]: info: Starting Streaming Daemon Apr 30 21:19:48 volumio-x86 sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 30 21:19:48 volumio-x86 sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 30 21:19:48 volumio-x86 sudo[2126]: pam_unix(sudo:session): session closed for user root Apr 30 21:19:48 volumio-x86 volumio[884]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreStateMachine::ClearQueue Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreStateMachine::stop Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 21:19:48 volumio-x86 volumio[884]: info: CorePlayQueue::clearPlayQueue Apr 30 21:19:48 volumio-x86 volumio[884]: info: CorePlayQueue::saveQueue Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushQueue Apr 30 21:19:48 volumio-x86 volumio[884]: Upnp client error: Error: This socket has been ended by the other party Apr 30 21:19:48 volumio-x86 volumio[884]: info: Apr 30 21:19:48 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:48 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:48 volumio-x86 volumio[884]: error: Cannot start Volumio Streaming Daemon Apr 30 21:19:48 volumio-x86 volumio[884]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 30 21:19:48 volumio-x86 volumio[884]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 30 21:19:48 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:19:48 volumio-x86 volumio[884]: info: ------------------------------ 95ms Apr 30 21:19:48 volumio-x86 volumio[884]: 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: 6 Apr 30 21:19:48 volumio-x86 volumio[884]: 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: 7 Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:48 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:48 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:48 volumio-x86 volumio[884]: info: Starting UPNP Playback Apr 30 21:19:48 volumio-x86 volumio[884]: info: Preparing playback through UPNP Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:19:48 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:19:48 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 21:19:49 volumio-x86 volumio[884]: STREAMING PROXY: Starting server on port 3245 Apr 30 21:19:49 volumio-x86 volumio[884]: Node JS runtime: 14 Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 4ms Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 1ms Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 1ms Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 1ms Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:49 volumio-x86 volumio[884]: info: Apr 30 21:19:49 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:19:49 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:19:49 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:19:53 volumio-x86 ntpd[870]: Soliciting pool server 78.46.102.180 Apr 30 21:19:53 volumio-x86 ntpd[870]: Soliciting pool server 119.28.183.184 Apr 30 21:19:54 volumio-x86 ntpd[870]: Soliciting pool server 162.159.200.1 Apr 30 21:19:54 volumio-x86 ntpd[870]: Soliciting pool server 193.182.111.142 Apr 30 21:19:55 volumio-x86 ntpd[870]: Soliciting pool server 2606:4700:f1::1 Apr 30 21:20:00 volumio-x86 volumio[884]: info: Apr 30 21:20:00 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:00 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 30 21:20:00 volumio-x86 volumio[884]: info: sendMpdCommand status took 14 milliseconds Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:00 volumio-x86 volumio[884]: info: sendMpdCommand clearerror took 5 milliseconds Apr 30 21:20:00 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:00 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"好薇 - 阿瓦人民唱新歌","artist":"好薇","album":null,"uri":"http://fsandroid.kugou.com/202504302119/1483a6e5e53c75b2c2ceaf6d0364f31e/v3/e695773335ca0663a6952e330b8d42ab/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx318946946_quviper_tape_s567271234.mp3","trackType":"mp3"} Apr 30 21:20:00 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService stop Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus stop Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: No code Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:00 volumio-x86 volumio[884]: info: ------------------------------ 79ms Apr 30 21:20:02 volumio-x86 ntpd[870]: Soliciting pool server 193.182.111.141 Apr 30 21:20:03 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 30 21:20:03 volumio-x86 volumio[884]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 30 21:20:03 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:20:05 volumio-x86 volumio[884]: info: Clearing queue after UPNP request Apr 30 21:20:05 volumio-x86 volumio[884]: info: CoreStateMachine::ClearQueue Apr 30 21:20:05 volumio-x86 volumio[884]: info: CoreStateMachine::stop Apr 30 21:20:05 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 21:20:05 volumio-x86 volumio[884]: info: CorePlayQueue::clearPlayQueue Apr 30 21:20:05 volumio-x86 volumio[884]: info: CorePlayQueue::saveQueue Apr 30 21:20:05 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:05 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushQueue Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 4ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: Starting UPNP Playback Apr 30 21:20:06 volumio-x86 volumio[884]: info: Preparing playback through UPNP Apr 30 21:20:06 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:20:06 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:20:06 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 3ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 5ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 3ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:06 volumio-x86 volumio[884]: info: Apr 30 21:20:06 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:06 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:06 volumio-x86 volumio[884]: info: ------------------------------ 1ms Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetBrowseSources Apr 30 21:20:08 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 30 21:20:15 volumio-x86 volumio[884]: info: Apr 30 21:20:15 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:15 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:15 volumio-x86 volumio[884]: info: Apr 30 21:20:15 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:15 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:15 volumio-x86 volumio[884]: info: Apr 30 21:20:15 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:15 volumio-x86 volumio[884]: info: sendMpdCommand status took 48 milliseconds Apr 30 21:20:15 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:15 volumio-x86 volumio[884]: info: ------------------------------ 13ms Apr 30 21:20:15 volumio-x86 volumio[884]: info: sendMpdCommand status took 10 milliseconds Apr 30 21:20:15 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:15 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"最美","artist":"张玮伽","album":"无情伤","uri":"http://fsandroid.kugou.com/202504302120/392414f04bbea019d99dd4ea72fa159b/v3/3554be361ad7441334ce771e4bf9ea44/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx28309187_quviper_tape_s421213457.mp3","trackType":"mp3"} Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus stop Apr 30 21:20:15 volumio-x86 volumio[884]: info: ------------------------------ 81ms Apr 30 21:20:15 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:15 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":56,"duration":289,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"最美","artist":"张玮伽","album":"无情伤","uri":"http://fsandroid.kugou.com/202504302120/392414f04bbea019d99dd4ea72fa159b/v3/3554be361ad7441334ce771e4bf9ea44/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx28309187_quviper_tape_s421213457.mp3","trackType":"mp3"} Apr 30 21:20:15 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:20:15 volumio-x86 volumio[884]: info: Received an update from plugin. extracting info from payload Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:15 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:16 volumio-x86 volumio[884]: info: ------------------------------ 152ms Apr 30 21:20:16 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:16 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:16 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:16 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:16 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:16 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:18 volumio-x86 ntpd[870]: Soliciting pool server 45.76.221.157 Apr 30 21:20:19 volumio-x86 volumio[884]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Apr 30 21:20:19 volumio-x86 volumio[884]: info: Retrying Login Due to Network Error Apr 30 21:20:25 volumio-x86 volumio[884]: info: Apr 30 21:20:25 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:25 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:25 volumio-x86 volumio[884]: info: sendMpdCommand status took 2 milliseconds Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:25 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:25 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最美","artist":"张玮伽","album":"无情伤","uri":"http://fsandroid.kugou.com/202504302120/392414f04bbea019d99dd4ea72fa159b/v3/3554be361ad7441334ce771e4bf9ea44/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx28309187_quviper_tape_s421213457.mp3","trackType":"mp3"} Apr 30 21:20:25 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService stop Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::stPlaybackTimer Apr 30 21:20:25 volumio-x86 volumio[884]: info: ------------------------------ 41ms Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: Clearing queue after UPNP request Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::ClearQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::stop Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 30 21:20:25 volumio-x86 volumio[884]: info: CorePlayQueue::clearPlayQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: CorePlayQueue::saveQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushQueue Apr 30 21:20:25 volumio-x86 volumio[884]: info: Apr 30 21:20:25 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:25 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:25 volumio-x86 volumio[884]: error: updateQueue error: null Apr 30 21:20:25 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:25 volumio-x86 volumio[884]: info: Starting UPNP Playback Apr 30 21:20:25 volumio-x86 volumio[884]: info: Preparing playback through UPNP Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetState Apr 30 21:20:25 volumio-x86 volumio[884]: info: CorePlayQueue::getTrack 0 Apr 30 21:20:25 volumio-x86 volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 3ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 1ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 2ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand status took 12 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces system playlist update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Ignoring MPD Status Update Apr 30 21:20:26 volumio-x86 volumio[884]: info: Apr 30 21:20:26 volumio-x86 volumio[884]: ---------------------------- MPD announces state update: player Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::getState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand status Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand status took 32 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 30 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Ŀ 4","artist":"δ֪","album":"δ֪Ƭ (2021/9/27 20:55:38)","uri":"http://fsandroid.kugou.com/202504302120/245b59208ebf76f57775793d1d84d6a7/v3/e695773335ca0663a6952e330b8d42ab/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx318946946_quviper_tape_s567271234.mp3","trackType":"mp3"} Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus stop Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 66ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 47ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand status took 24 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ŀ 4","artist":"δ֪","album":"δ֪Ƭ (2021/9/27 20:55:38)","uri":"http://fsandroid.kugou.com/202504302120/245b59208ebf76f57775793d1d84d6a7/v3/e695773335ca0663a6952e330b8d42ab/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx318946946_quviper_tape_s567271234.mp3","trackType":"mp3"} Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:20:26 volumio-x86 volumio[884]: info: Received an update from plugin. extracting info from payload Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 134ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: ControllerMpd::parseTrackInfo Apr 30 21:20:26 volumio-x86 volumio[884]: info: ControllerMpd::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::servicePushState Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: In UPNP mode Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":218,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ŀ 4","artist":"δ֪","album":"δ֪Ƭ (2021/9/27 20:55:38)","uri":"http://fsandroid.kugou.com/202504302120/245b59208ebf76f57775793d1d84d6a7/v3/e695773335ca0663a6952e330b8d42ab/yp/full/ap1005_us997964742_df1j8sjg3rd22k4foy8o4pzhpp_pi2_mx318946946_quviper_tape_s567271234.mp3","trackType":"mp3"} Apr 30 21:20:26 volumio-x86 volumio[884]: verbose: CURRENT POSITION 0 Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState stateService play Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::syncState currentStatus play Apr 30 21:20:26 volumio-x86 volumio[884]: info: Received an update from plugin. extracting info from payload Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::pushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioPushState Apr 30 21:20:26 volumio-x86 volumio[884]: info: ------------------------------ 208ms Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreCommandRouter::volumioGetQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CoreStateMachine::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: info: CorePlayQueue::getQueue Apr 30 21:20:26 volumio-x86 volumio[884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 21:20:26 volumio-x86 volumio[884]: Error: connect EHOSTUNREACH 198.44.185.131:443 Apr 30 21:20:26 volumio-x86 volumio[884]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 30 21:20:26 volumio-x86 volumio[884]: errno: -113, Apr 30 21:20:26 volumio-x86 volumio[884]: code: 'EHOSTUNREACH', Apr 30 21:20:26 volumio-x86 volumio[884]: syscall: 'connect', Apr 30 21:20:26 volumio-x86 volumio[884]: address: '198.44.185.131', Apr 30 21:20:26 volumio-x86 volumio[884]: port: 443 Apr 30 21:20:26 volumio-x86 volumio[884]: } Apr 30 21:20:26 volumio-x86 volumio[884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 30 21:20:27 volumio-x86 sudo[2545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 21:19 Apr 30 21:20:27 volumio-x86 sudo[2545]: 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"