-- Logs begin at Mon 2025-12-22 12:18:26 CST, end at Mon 2025-12-22 12:20:47 CST. --
Dec 22 12:19:06 volumio systemd[1]: systemd-timedated.service: Succeeded.
Dec 22 12:19:11 volumio volumio[1186]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory
Dec 22 12:19:11 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 22 12:19:11 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 22 12:19:11 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 22 12:19:11 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 22 12:19:11 volumio volumio-remote-updater[777]: Test mode enabled
Dec 22 12:19:11 volumio volumio-remote-updater[777]: No alpha test mode
Dec 22 12:19:11 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 22 12:19:12 volumio volumio[1186]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Fix for Streaming Services playback in case of network interruptions
\n
\nNEW ADDITIONS
\n\n- BLE-based onboarding setup
\n
\n","title":"Update v3.887","updateavailable":true}
Dec 22 12:19:12 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 22 12:19:12 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 22 12:19:12 volumio volumio[1186]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Dec 22 12:19:12 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:12 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:14 volumio systemd[1]: Started My Shell Script.
Dec 22 12:19:14 volumio systemd[1]: Reached target Multi-User System.
Dec 22 12:19:14 volumio systemd[1]: Reached target Graphical Interface.
Dec 22 12:19:14 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec 22 12:19:14 volumio sudo[2050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/bash /home/volumio/scripts/peppy.sh
Dec 22 12:19:14 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 22 12:19:14 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Dec 22 12:19:14 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec 22 12:19:14 volumio systemd[1]: Startup finished in 7.461s (firmware) + 2.760s (loader) + 9.892s (kernel) + 1min 3.872s (userspace) = 1min 23.986s.
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 22 12:19:17 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 22 12:19:18 volumio volumio[1186]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 22 12:19:18 volumio volumio[1186]: info: Completed starting MyVolumio Plugin
Dec 22 12:19:18 volumio volumio[1186]: [Metrics] CommandRouter: 45s 946.92ms
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 22 12:19:18 volumio volumio[1186]: info: VolumeController:: Setting startup Volume 40
Dec 22 12:19:18 volumio volumio[1186]: info: VolumeController::SetAlsaVolume40
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::Close All Modals sent
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::Close All Modals sent
Dec 22 12:19:18 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:19:18 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 22 12:19:18 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:19:19 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 22 12:19:19 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 22 12:19:20 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 22 12:19:20 volumio ntpd[970]: Soliciting pool server 95.111.202.5
Dec 22 12:19:21 volumio ntpd[970]: Soliciting pool server 108.59.2.24
Dec 22 12:19:22 volumio ntpd[970]: Soliciting pool server 162.159.200.1
Dec 22 12:19:22 volumio ntpd[970]: Soliciting pool server 117.80.231.60
Dec 22 12:19:23 volumio ntpd[970]: Soliciting pool server 193.182.111.12
Dec 22 12:19:23 volumio ntpd[970]: Soliciting pool server 119.28.183.184
Dec 22 12:19:23 volumio ntpd[970]: Soliciting pool server 5.79.108.34
Dec 22 12:19:24 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:24 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:24 volumio ntpd[970]: Soliciting pool server 84.16.73.33
Dec 22 12:19:24 volumio ntpd[970]: Soliciting pool server 84.16.67.12
Dec 22 12:19:24 volumio ntpd[970]: Soliciting pool server 210.28.130.4
Dec 22 12:19:25 volumio ntpd[970]: Soliciting pool server 119.28.206.193
Dec 22 12:19:25 volumio ntpd[970]: Soliciting pool server 240e:982:2000:c::371:3cc
Dec 22 12:19:25 volumio ntpd[970]: Soliciting pool server 111.230.189.174
Dec 22 12:19:25 volumio volumio[1186]: info: BOOT COMPLETED
Dec 22 12:19:26 volumio ntpd[970]: Soliciting pool server 185.209.85.222
Dec 22 12:19:26 volumio ntpd[970]: Soliciting pool server 193.182.111.14
Dec 22 12:19:29 volumio ntpd[970]: receive: Unexpected origin timestamp 0xecf34550.74f4da1d does not match aorg 0000000000.00000000 from server@95.111.202.5 xmt 0xecf34551.7437f510
Dec 22 12:19:30 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:30 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:30 volumio volumio[1186]: info: CoreCommandRouter::volumioGetQueue
Dec 22 12:19:30 volumio volumio[1186]: info: CoreStateMachine::getQueue
Dec 22 12:19:30 volumio volumio[1186]: info: CorePlayQueue::getQueue
Dec 22 12:19:35 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:35 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:35 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 22 12:19:35 volumio volumio[1186]: info: CURURI: music-library/INTERNAL
Dec 22 12:19:35 volumio volumio[1186]: info: Preload queue cleared
Dec 22 12:19:40 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:40 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:45 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:45 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:50 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:50 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:19:55 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:19:55 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: Preload queue cleared
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioReplaceandPlayItems
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::ClearQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::stop
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::clearPlayQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::saveQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::addQueueItems
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::addQueueItems
Dec 22 12:20:00 volumio volumio[1186]: info: Preload queue cleared
Dec 22 12:20:00 volumio volumio[1186]: info: Adding Item to queue: music-library/INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]
Dec 22 12:20:00 volumio volumio[1186]: info: Exploding uri music-library/INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE] in service mpd
Dec 22 12:20:00 volumio volumio[1186]: info: ALBUMART /albumart?cacheid=870&path=%2Fmnt%2FINTERNAL%2F%E7%AB%A5%E4%B8%BD%E3%80%8A%E6%A2%A6%E4%B8%AD%E8%8A%B1%20DSD%E3%80%8B%5B%E6%AD%A3%E7%89%88CD%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2FCDImage.wav&metadata=false
Dec 22 12:20:00 volumio volumio[1186]: info: URI /mnt/INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::saveQueue
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::updateTrackBlock
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrackBlock
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPlay
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::play index 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::stop
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::play index undefined
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::startPlaybackTimer
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand stop
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand stop took 2 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand clear
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand clear took 7 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav"
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: error: updateQueue error: null
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 11ms
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand add "INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav" took 9 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 8ms
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 6ms
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand play
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: error: Upnp client error: Error: This socket has been ended by the other party
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 12ms
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 10ms
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand play took 7 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 6ms
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 5ms
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces system playlist update
Dec 22 12:20:00 volumio volumio[1186]: info: Ignoring MPD Status Update
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 2ms
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 375 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 379 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 378 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 376 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info:
Dec 22 12:20:00 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 14 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 11 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:00 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus stop
Dec 22 12:20:00 volumio volumio[1186]: info: ------------------------------ 403ms
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 18 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 18 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 18 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 16 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 14 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand status took 14 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 9 milliseconds
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:00 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:00 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:00 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:00 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:00 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:00 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:00 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:00 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:00 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:00 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:00 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:00 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 480ms
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 484ms
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 483ms
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 111ms
Dec 22 12:20:01 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 87 milliseconds
Dec 22 12:20:01 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 92 milliseconds
Dec 22 12:20:01 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 93 milliseconds
Dec 22 12:20:01 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:01 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:01 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:01 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:01 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:01 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:01 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:01 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:01 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState stateService play
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus play
Dec 22 12:20:01 volumio volumio[1186]: info: Received an update from plugin. extracting info from payload
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 234ms
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 238ms
Dec 22 12:20:01 volumio volumio[1186]: info: ------------------------------ 238ms
Dec 22 12:20:01 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:20:01 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:01 volumio sudo[2241]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh
Dec 22 12:20:01 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 22 12:20:06 volumio volumio[1186]: 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
Dec 22 12:20:06 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:20:06 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:43 volumio kernel: usb 1-3.4: USB disconnect, device number 4
Dec 22 12:20:43 volumio volumio[1186]: info:
Dec 22 12:20:43 volumio volumio[1186]: ---------------------------- USB Audio Device Detached
Dec 22 12:20:43 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach
Dec 22 12:20:43 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 22 12:20:43 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: raat , onStop
Dec 22 12:20:44 volumio kernel: usb 1-3.4: new full-speed USB device number 5 using xhci_hcd
Dec 22 12:20:44 volumio kernel: usb 1-3.4: not running at top speed; connect to a high speed hub
Dec 22 12:20:44 volumio kernel: usb 1-3.4: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5
Dec 22 12:20:44 volumio kernel: usb 1-3.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Dec 22 12:20:44 volumio kernel: usb 1-3.4: Product: xCORE USB Audio 2.0
Dec 22 12:20:44 volumio kernel: usb 1-3.4: Manufacturer: XMOS
Dec 22 12:20:45 volumio kernel: usb 1-3.4: 1:3 : unsupported format bits 0x100000000
Dec 22 12:20:46 volumio volumio[1186]: info:
Dec 22 12:20:46 volumio volumio[1186]: ---------------------------- USB Audio Device Attached
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::Close All Modals sent
Dec 22 12:20:46 volumio volumio[1186]: info: Preparing to save Alsa Options, stopping services first
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioGetState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioPause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::servicePause
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::servicePause
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::pause
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand pause
Dec 22 12:20:46 volumio volumio[1186]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false}
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 22 12:20:46 volumio volumio[1186]: info: Setting default mixerSoftMaster for Softvolume device
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Dec 22 12:20:46 volumio volumio[1186]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Dec 22 12:20:46 volumio volumio[1186]: info: Disabling external Volume Control
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 22 12:20:46 volumio volumio[1186]: info: Enable softmixer device for audio device undefined
Dec 22 12:20:46 volumio volumio[1186]: info:
Dec 22 12:20:46 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand pause took 276 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:46 volumio volumio[1186]: info:
Dec 22 12:20:46 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:46 volumio volumio[1186]: info:
Dec 22 12:20:46 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:46 volumio volumio[1186]: info:
Dec 22 12:20:46 volumio volumio[1186]: ---------------------------- MPD announces state update: player
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::getState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand status
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand status took 10 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand status took 7 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand status took 6 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand status took 5 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::parseState
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 22 12:20:46 volumio volumio[1186]: info: Software Volume ALSA configuration written
Dec 22 12:20:46 volumio volumio[1186]: info: Preparing to generate the ALSA configuration file
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 22 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 21 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 22 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: info: sendMpdCommand playlistinfo took 20 milliseconds
Dec 22 12:20:46 volumio volumio[1186]: verbose: ControllerMpd::parseTrackInfo
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46306,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:46 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46306,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:46 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46306,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:46 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer
Dec 22 12:20:46 volumio volumio[1186]: info: ControllerMpd::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::servicePushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46306,"duration":3061,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"CDImage.wav","artist":null,"album":null,"uri":"INTERNAL/童丽《梦中花 DSD》[正版CD低速原抓WAV+CUE]/CDImage.wav","trackType":"wav"}
Dec 22 12:20:46 volumio volumio[1186]: verbose: CURRENT POSITION 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState stateService pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::syncState currentStatus pause
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::pushState
Dec 22 12:20:46 volumio volumio[1186]: info: CorePlayQueue::getTrack 0
Dec 22 12:20:46 volumio volumio[1186]: info: CoreCommandRouter::volumioPushState
Dec 22 12:20:46 volumio volumio[1186]: info: CoreStateMachine::stPlaybackTimer
Dec 22 12:20:46 volumio volumio[1186]: info: ------------------------------ 92ms
Dec 22 12:20:46 volumio volumio[1186]: info: ------------------------------ 90ms
Dec 22 12:20:46 volumio volumio[1186]: info: ------------------------------ 89ms
Dec 22 12:20:46 volumio volumio[1186]: info: ------------------------------ 88ms
Dec 22 12:20:46 volumio volumio[1186]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Dec 22 12:20:46 volumio volumio[1186]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
Dec 22 12:20:46 volumio volumio[1186]: info: Reading ALSA contributions from plugins.
Dec 22 12:20:46 volumio volumio[1186]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 22 12:20:46 volumio volumio[1186]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
Dec 22 12:20:46 volumio volumio[1186]: at Object.readdirSync (fs.js:1048:3)
Dec 22 12:20:46 volumio volumio[1186]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
Dec 22 12:20:46 volumio volumio[1186]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
Dec 22 12:20:46 volumio volumio[1186]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
Dec 22 12:20:46 volumio volumio[1186]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
Dec 22 12:20:46 volumio volumio[1186]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
Dec 22 12:20:46 volumio volumio[1186]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Dec 22 12:20:46 volumio volumio[1186]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
Dec 22 12:20:46 volumio volumio[1186]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
Dec 22 12:20:46 volumio volumio[1186]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Dec 22 12:20:46 volumio volumio[1186]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Dec 22 12:20:46 volumio volumio[1186]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
Dec 22 12:20:46 volumio volumio[1186]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
Dec 22 12:20:46 volumio volumio[1186]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
Dec 22 12:20:46 volumio volumio[1186]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
Dec 22 12:20:46 volumio volumio[1186]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
Dec 22 12:20:46 volumio volumio[1186]: errno: -20,
Dec 22 12:20:46 volumio volumio[1186]: syscall: 'scandir',
Dec 22 12:20:46 volumio volumio[1186]: code: 'ENOTDIR',
Dec 22 12:20:46 volumio volumio[1186]: path: '/tmp/peppyrunning'
Dec 22 12:20:46 volumio volumio[1186]: }
Dec 22 12:20:46 volumio volumio[1186]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 22 12:20:47 volumio sudo[2519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-22 12:19
Dec 22 12:20:47 volumio sudo[2519]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET"
VOLUMIO_VERSION="3.886"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"