-- Logs begin at Sun 2025-05-18 14:38:55 CST, end at Sun 2025-05-18 14:41:42 CST. -- May 18 14:40:01 volumio volumio[1071]: Cannot compose Albumart path May 18 14:40:01 volumio volumio[1071]: Cannot compose Albumart path May 18 14:40:01 volumio volumio[1071]: Cannot compose Albumart path May 18 14:40:01 volumio volumio[1071]: Cannot compose Albumart path May 18 14:40:02 volumio volumio[1071]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 18 14:40:02 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:02 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:02 volumio volumio[1071]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 18 14:40:03 volumio volumio[1071]: info: MyVolumio token set successfully May 18 14:40:03 volumio volumio[1071]: info: MYVOLUMIO: Adding device May 18 14:40:03 volumio volumio[1071]: info: MYVOLUMIO: Evaluating Server May 18 14:40:04 volumio volumio[1071]: info: MyVolumio status changed May 18 14:40:04 volumio volumio[1071]: info: Streaming services startup May 18 14:40:04 volumio volumio[1071]: info: Starting Streaming Daemon May 18 14:40:04 volumio volumio[1071]: info: Removing browser output: myVolumio user plan is not superstar May 18 14:40:04 volumio volumio[1071]: info: Removing audio output: May 18 14:40:04 volumio volumio[1071]: info: Stoppping Tunnel 1 May 18 14:40:04 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 14:40:04 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:04 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 14:40:04 volumio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 18 14:40:04 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:04 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 18 14:40:04 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root May 18 14:40:04 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root May 18 14:40:05 volumio volumio[1071]: info: Remote SSH Stopped May 18 14:40:05 volumio volumio[1071]: error: Cannot start Volumio Streaming Daemon May 18 14:40:05 volumio volumio[1071]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 18 14:40:05 volumio volumio[1071]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 18 14:40:05 volumio volumio[1071]: info: Setting Geolocation for MyVolumio to us2 May 18 14:40:05 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:05 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:05 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:05 volumio volumio[1071]: info: CoreCommandRouter::getUIConfigOnPlugin May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:06 volumio volumio[1071]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 18 14:40:06 volumio volumio[1071]: 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: 14 May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:06 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:06 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 14:40:06 volumio volumio[1071]: info: Updating MyVolumio device info May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetVisibleSources May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:06 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 14:40:06 volumio volumio[1071]: info: Received Get System Info May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:06 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:06 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:06 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: Listing playlists May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 14:40:07 volumio volumio[1071]: info: Received Get System Info May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:07 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 14:40:07 volumio volumio[1071]: info: Received Get System Info May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:07 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetVisibleSources May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 14:40:07 volumio volumio[1071]: info: Received Get System Info May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:07 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:07 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:07 volumio volumio[1071]: info: Listing playlists May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 14:40:07 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 18 14:40:07 volumio volumio[1071]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 18 14:40:08 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 14:40:08 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 18 14:40:08 volumio volumio[1071]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 18 14:40:08 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:08 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:09 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 14:40:09 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:09 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 18 14:40:09 volumio volumio-remote-updater[722]: No test mode May 18 14:40:09 volumio volumio-remote-updater[722]: No alpha test mode May 18 14:40:09 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 18 14:40:10 volumio volumio[1071]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 18 14:40:10 volumio volumio[1071]: info: MYVOLUMIO: Adding device May 18 14:40:10 volumio volumio[1071]: info: MYVOLUMIO: Evaluating Server May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 14:40:10 volumio volumio[1071]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 18 14:40:10 volumio volumio[1071]: info: Received Get System Version May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 14:40:10 volumio volumio[1071]: info: Received Get System Info May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:10 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:10 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:10 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:12 volumio volumio[1071]: info: Setting Geolocation for MyVolumio to us2 May 18 14:40:12 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:12 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:12 volumio volumio[1071]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 18 14:40:13 volumio volumio[1071]: info: Updating MyVolumio device info May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:13 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::volumioGetBrowseSources May 18 14:40:13 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 14:40:13 volumio volumio[1071]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 18 14:40:16 volumio volumio[1071]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 18 14:40:16 volumio volumio[1071]: info: Completed starting MyVolumio Plugin May 18 14:40:16 volumio volumio[1071]: [Metrics] CommandRouter: 53s 191.59ms May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::volumiosetStartupVolume May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:16 volumio volumio[1071]: info: VolumeController:: Setting startup Volume 80 May 18 14:40:16 volumio volumio[1071]: info: VolumeController::SetAlsaVolume80 May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::Close All Modals sent May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::Close All Modals sent May 18 14:40:16 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:16 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:40:16 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:17 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 18 14:40:17 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 14:40:17 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 18 14:40:21 volumio volumio[1071]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 18 14:40:21 volumio volumio[1071]: info: Preparing to generate the ALSA configuration file May 18 14:40:21 volumio volumio[1071]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf May 18 14:40:21 volumio volumio[1071]: info: Reading ALSA contributions from plugins. May 18 14:40:21 volumio volumio[1071]: info: Asound.conf file written May 18 14:40:21 volumio sudo[2045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 18 14:40:21 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:21 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root May 18 14:40:21 volumio volumio[1071]: info: Output device has changed, restarting MPD May 18 14:40:21 volumio sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 18 14:40:21 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:21 volumio volumio[1071]: info: Output device has changed, restarting Shairport Sync May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:21 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:21 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 14:40:21 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:21 volumio systemd[1]: Stopping Music Player Daemon... May 18 14:40:21 volumio volumio[1071]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 14:40:21 volumio volumio[1071]: info: MPD Permissions set May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:21 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:21 volumio systemd[1]: mpd.service: Succeeded. May 18 14:40:21 volumio systemd[1]: Stopped Music Player Daemon. May 18 14:40:22 volumio systemd[1]: Starting Music Player Daemon... May 18 14:40:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:40:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 18 14:40:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:22 volumio volumio[1071]: info: Starting Shairport Sync May 18 14:40:22 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 14:40:22 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 18 14:40:22 volumio sudo[2058]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 14:40:22 volumio sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 14:40:22 volumio systemd[1]: shairport-sync.service: Succeeded. May 18 14:40:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 18 14:40:22 volumio sudo[2058]: pam_unix(sudo:session): session closed for user root May 18 14:40:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 14:40:22 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root May 18 14:40:22 volumio volumio[1071]: info: Shairport-Sync Started May 18 14:40:22 volumio sudo[2071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 18 14:40:22 volumio sudo[2071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 18 14:40:22 volumio systemd[1]: mpd.service: Succeeded. May 18 14:40:22 volumio systemd[1]: Stopped Music Player Daemon. May 18 14:40:22 volumio systemd[1]: Starting Music Player Daemon... May 18 14:40:22 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:40:22 volumio sudo[2076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 18 14:40:22 volumio sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:40:22 volumio sudo[2076]: pam_unix(sudo:session): session closed for user root May 18 14:40:23 volumio volumio[1071]: info: BOOT COMPLETED May 18 14:40:24 volumio ntpd[1062]: Soliciting pool server 119.28.183.184 May 18 14:40:24 volumio mpd[2078]: May 18 14:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 18 14:40:24 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:24 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:25 volumio systemd[1]: Started Music Player Daemon. May 18 14:40:25 volumio sudo[2071]: pam_unix(sudo:session): session closed for user root May 18 14:40:25 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root May 18 14:40:25 volumio volumio[1071]: error: MPD error: The expression evaluated to a falsy value: May 18 14:40:25 volumio volumio[1071]: assert.ok(self.idling) May 18 14:40:25 volumio volumio[1071]: error: The expression evaluated to a falsy value: May 18 14:40:25 volumio volumio[1071]: assert.ok(self.idling) May 18 14:40:25 volumio volumio[1071]: error: updateQueue error: null May 18 14:40:26 volumio ntpd[1062]: Soliciting pool server 117.80.231.60 May 18 14:40:27 volumio ntpd[1062]: Soliciting pool server 49.213.184.242 May 18 14:40:27 volumio ntpd[1062]: Soliciting pool server 202.118.1.130 May 18 14:40:28 volumio ntpd[1062]: Soliciting pool server 183.177.72.202 May 18 14:40:28 volumio ntpd[1062]: Soliciting pool server 202.118.1.81 May 18 14:40:29 volumio ntpd[1062]: Soliciting pool server 211.68.71.26 May 18 14:40:30 volumio ntpd[1062]: Soliciting pool server 84.16.67.12 May 18 14:40:35 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:35 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:40:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:40:36 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:40:36 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:36 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:40:47 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:47 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:47 volumio volumio[1071]: info: Clearing queue after UPNP request May 18 14:40:48 volumio volumio[1071]: info: CoreStateMachine::ClearQueue May 18 14:40:48 volumio volumio[1071]: info: CoreStateMachine::stop May 18 14:40:48 volumio volumio[1071]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 14:40:48 volumio volumio[1071]: info: CorePlayQueue::clearPlayQueue May 18 14:40:48 volumio volumio[1071]: info: CorePlayQueue::saveQueue May 18 14:40:48 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:48 volumio volumio[1071]: info: CoreCommandRouter::volumioPushQueue May 18 14:40:48 volumio volumio[1071]: error: Upnp client error: Error: This socket has been ended by the other party May 18 14:40:49 volumio volumio[1071]: info: Starting UPNP Playback May 18 14:40:49 volumio volumio[1071]: info: Preparing playback through UPNP May 18 14:40:49 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:49 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:40:49 volumio volumio[1071]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 9ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 6ms May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 6ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 4ms May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 6ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 4ms May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 7ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 4ms May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 6ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 4ms May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces system playlist update May 18 14:40:50 volumio volumio[1071]: info: Ignoring MPD Status Update May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 6ms May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 3ms May 18 14:40:50 volumio volumio[1635]: 78 May 18 14:40:50 volumio volumio[1635]: % Total % Received % Xferd Average Speed Time Time Time Current May 18 14:40:50 volumio volumio[1635]: Dload Upload Total Spent Left Speed May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- Client requests Volume 78 May 18 14:40:50 volumio volumio[1071]: info: VolumeController::SetAlsaVolume78 May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:50 volumio volumio[1635]: [158B blob data] May 18 14:40:50 volumio volumio[1635]: {"time":1747550450610,"response":"volume Success"}78 May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand status took 117 milliseconds May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand status took 125 milliseconds May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:40:50 volumio volumio[1071]: info: May 18 14:40:50 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand status took 23 milliseconds May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 18 milliseconds May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 12 milliseconds May 18 14:40:50 volumio volumio[1071]: info: sendMpdCommand status took 7 milliseconds May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::parseTrackInfo May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:40:50 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:40:50 volumio volumio[1071]: verbose: In UPNP mode May 18 14:40:50 volumio volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"金风玉露","artist":"梅小琴","album":"潇洒","uri":"http://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:40:50 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::syncState stateService play May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus stop May 18 14:40:50 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:40:50 volumio volumio[1071]: verbose: In UPNP mode May 18 14:40:50 volumio volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"金风玉露","artist":"梅小琴","album":"潇洒","uri":"http://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:40:50 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::syncState stateService play May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus play May 18 14:40:50 volumio volumio[1071]: info: Received an update from plugin. extracting info from payload May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:50 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:50 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:50 volumio volumio[1071]: info: ------------------------------ 224ms May 18 14:40:51 volumio volumio[1071]: info: ------------------------------ 281ms May 18 14:40:51 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 135 milliseconds May 18 14:40:51 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 129 milliseconds May 18 14:40:51 volumio volumio[1071]: verbose: ControllerMpd::parseTrackInfo May 18 14:40:51 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:40:51 volumio volumio[1071]: verbose: In UPNP mode May 18 14:40:51 volumio volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"金风玉露","artist":"梅小琴","album":"潇洒","uri":"http://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:40:51 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::syncState stateService play May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus play May 18 14:40:51 volumio volumio[1071]: info: Received an update from plugin. extracting info from payload May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:51 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:40:51 volumio volumio[1071]: verbose: In UPNP mode May 18 14:40:51 volumio volumio[1071]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"917 Kbps","isStreaming":false,"title":"金风玉露","artist":"梅小琴","album":"潇洒","uri":"http://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:40:51 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::syncState stateService play May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus play May 18 14:40:51 volumio volumio[1071]: info: Received an update from plugin. extracting info from payload May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:40:51 volumio volumio[1071]: info: ------------------------------ 625ms May 18 14:40:51 volumio volumio[1071]: info: ------------------------------ 600ms May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:51 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:40:51 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:40:51 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:40:58 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:40:58 volumio sudo[2196]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 18 14:40:58 volumio sudo[2196]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:41:02 volumio volumio[1071]: 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: 9 May 18 14:41:02 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:41:04 volumio volumio[1071]: 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: 10 May 18 14:41:04 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:41:05 volumio volumio[1071]: info: VolumeController::SetAlsaVolume100 May 18 14:41:05 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:41:05 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:41:05 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:05 volumio volumio[1071]: info: CoreCommandRouter::volumioGetQueue May 18 14:41:05 volumio volumio[1071]: info: CoreStateMachine::getQueue May 18 14:41:05 volumio volumio[1071]: info: CorePlayQueue::getQueue May 18 14:41:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:41:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:41:06 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:41:06 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:41:06 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:41:30 volumio ntpd[1062]: Soliciting pool server 162.159.200.1 May 18 14:41:33 volumio ntpd[1062]: Soliciting pool server 218.35.163.245 May 18 14:41:34 volumio ntpd[1062]: Soliciting pool server 122.117.253.246 May 18 14:41:36 volumio ntpd[1062]: Soliciting pool server 84.16.67.12 May 18 14:41:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 18 14:41:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 18 14:41:36 volumio volumio[1071]: info: Discovery: Getting this device information May 18 14:41:36 volumio volumio[1071]: info: CoreCommandRouter::volumioGetState May 18 14:41:36 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 18 14:41:41 volumio volumio[1071]: info: May 18 14:41:41 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:41:41 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:41:41 volumio volumio[1071]: info: May 18 14:41:41 volumio volumio[1071]: ---------------------------- MPD announces state update: player May 18 14:41:41 volumio volumio[1071]: info: ControllerMpd::getState May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand status May 18 14:41:41 volumio volumio[1071]: info: sendMpdCommand status took 7 milliseconds May 18 14:41:41 volumio volumio[1071]: info: sendMpdCommand status took 4 milliseconds May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::parseState May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:41:41 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 14:41:41 volumio volumio[1071]: info: sendMpdCommand playlistinfo took 2 milliseconds May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::parseTrackInfo May 18 14:41:41 volumio volumio[1071]: verbose: ControllerMpd::parseTrackInfo May 18 14:41:41 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:41 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:41:41 volumio volumio[1071]: 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://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:41:41 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::syncState stateService stop May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus play May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::stPlaybackTimer May 18 14:41:41 volumio volumio[1071]: info: ControllerMpd::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::servicePushState May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:41 volumio volumio[1071]: info: CorePlayQueue::getTrack 0 May 18 14:41:41 volumio volumio[1071]: 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://192.168.31.126:50857/bridge-1.flac","trackType":"flac"} May 18 14:41:41 volumio volumio[1071]: verbose: CURRENT POSITION 0 May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::syncState stateService stop May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::syncState currentStatus stop May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:41 volumio volumio[1071]: info: No code May 18 14:41:41 volumio volumio[1071]: info: CoreStateMachine::pushState May 18 14:41:41 volumio volumio[1071]: info: CoreCommandRouter::volumioPushState May 18 14:41:41 volumio volumio[1071]: info: ------------------------------ 154ms May 18 14:41:41 volumio volumio[1071]: info: ------------------------------ 153ms May 18 14:41:41 volumio volumio[1071]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 14:41:41 volumio volumio[1071]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' May 18 14:41:41 volumio volumio[1071]: at Object.readdirSync (fs.js:1048:3) May 18 14:41:41 volumio volumio[1071]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) May 18 14:41:41 volumio volumio[1071]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) May 18 14:41:41 volumio volumio[1071]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) May 18 14:41:41 volumio volumio[1071]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) May 18 14:41:41 volumio volumio[1071]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) May 18 14:41:41 volumio volumio[1071]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) May 18 14:41:41 volumio volumio[1071]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) May 18 14:41:41 volumio volumio[1071]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) May 18 14:41:41 volumio volumio[1071]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) { May 18 14:41:41 volumio volumio[1071]: errno: -20, May 18 14:41:41 volumio volumio[1071]: syscall: 'scandir', May 18 14:41:41 volumio volumio[1071]: code: 'ENOTDIR', May 18 14:41:41 volumio volumio[1071]: path: '/tmp/peppyrunning' May 18 14:41:41 volumio volumio[1071]: } May 18 14:41:41 volumio volumio[1071]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 14:41:42 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 14:40 May 18 14:41:42 volumio sudo[2525]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"