-- Logs begin at Mon 2026-01-12 09:22:20 CST, end at Mon 2026-01-12 09:24:50 CST. -- Jan 12 09:23:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:23:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:23:01 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 09:23:03 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:23:04 volumio volumio[893]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 12 09:23:04 volumio volumio[893]: info: Completed starting MyVolumio Plugin Jan 12 09:23:04 volumio volumio[893]: info: BOOT COMPLETED Jan 12 09:23:04 volumio volumio[893]: [Metrics] CommandRouter: 39s 158.46ms Jan 12 09:23:04 volumio volumio[893]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 09:23:04 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:23:04 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:23:04 volumio volumio[893]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:23:04 volumio volumio[893]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:23:04 volumio volumio[893]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 12 09:23:04 volumio volumio[893]: aplay: main:828: audio open error: Device or resource busy Jan 12 09:23:05 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 09:23:05 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 09:23:05 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 09:23:10 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:23:10 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:23:10 volumio sudo[1927]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:23:10 volumio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:23:12 volumio sudo[1927]: pam_unix(sudo:session): session closed for user root Jan 12 09:23:14 volumio volumio[893]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 09:23:14 volumio volumio[893]: info: Retrying Login Due to Network Error Jan 12 09:23:23 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:23:23 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:23:23 volumio sudo[2013]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:23:23 volumio sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:23:24 volumio volumio[893]: info: MyVolumio login type: Token Jan 12 09:23:24 volumio sudo[2013]: pam_unix(sudo:session): session closed for user root Jan 12 09:23:35 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:23:35 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:23:35 volumio sudo[2057]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:23:35 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:23:37 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Jan 12 09:23:43 volumio volumio[893]: info: peppy_screensaver: Start PeppyMeter Jan 12 09:23:46 volumio volumio-remote-updater[662]: No test mode Jan 12 09:23:46 volumio volumio-remote-updater[662]: No alpha test mode Jan 12 09:23:46 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 09:23:46 volumio volumio[893]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

 

 



NEW ADDITIONS



 

 

 

","title":"Update v3.886","updateavailable":true} Jan 12 09:23:46 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 09:23:48 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:23:48 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:23:48 volumio sudo[2114]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:23:48 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:23:50 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Jan 12 09:23:54 volumio volumio[893]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 09:23:54 volumio volumio[893]: info: Retrying Login Due to Network Error Jan 12 09:24:01 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:24:01 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:24:01 volumio sudo[2158]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:24:01 volumio sudo[2158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:24:02 volumio sudo[2158]: pam_unix(sudo:session): session closed for user root Jan 12 09:24:04 volumio volumio[893]: info: MyVolumio login type: Token Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioNext Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::next Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::stPlaybackTimer Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::serviceStop Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 0 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::serviceStop Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::stop Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand stop took 15 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::play index undefined Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::startPlaybackTimer Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::clearAddPlayTracks NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::updateTrackBlock Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrackBlock Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand stop took 2 milliseconds Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 10 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand clear took 9 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 8 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 4 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 3 milliseconds Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand add "NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav" Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:24:09 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: No code Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:24:09 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: No code Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:24:09 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState stateService stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: No code Jan 12 09:24:09 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:09 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:09 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 76ms Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 73ms Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: error: updateQueue error: null Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 78ms Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand playlistinfo took 77 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand add "NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav" took 76 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 6ms Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 6ms Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 5ms Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand play Jan 12 09:24:09 volumio volumio[893]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 92ms Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces system playlist update Jan 12 09:24:09 volumio volumio[893]: info: Ignoring MPD Status Update Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 35ms Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand play took 31 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 30ms Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 29ms Jan 12 09:24:09 volumio volumio[893]: info: ------------------------------ 28ms Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 14 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 9 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 7 milliseconds Jan 12 09:24:09 volumio volumio[893]: info: sendMpdCommand status took 6 milliseconds Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:09 volumio volumio[893]: info: Jan 12 09:24:09 volumio volumio[893]: ---------------------------- MPD announces state update: player Jan 12 09:24:09 volumio volumio[893]: info: ControllerMpd::getState Jan 12 09:24:09 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand status took 650 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 647 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 648 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 647 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 647 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand status took 646 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand status took 644 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand status took 643 milliseconds Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseState Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 696ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 729ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 724ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 723ms Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 12 09:24:10 volumio volumio[893]: info: sendMpdCommand playlistinfo took 67 milliseconds Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: verbose: ControllerMpd::parseTrackInfo Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ControllerMpd::pushState Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::servicePushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02.??ʿɽ??","artist":null,"album":null,"uri":"NAS/CD1-210G/2023-冯小慈《诺言》2023头版限量编号24K金碟[低速整轨WAV](1)/02.富士山下.wav","trackType":"wav"} Jan 12 09:24:10 volumio volumio[893]: verbose: CURRENT POSITION 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState stateService play Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::syncState currentStatus play Jan 12 09:24:10 volumio volumio[893]: info: Received an update from plugin. extracting info from payload Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: CoreStateMachine::pushState Jan 12 09:24:10 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:10 volumio volumio[893]: info: CoreCommandRouter::volumioPushState Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 786ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 780ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 780ms Jan 12 09:24:10 volumio volumio[893]: info: ------------------------------ 779ms Jan 12 09:24:13 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:24:13 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:13 volumio sudo[2206]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:24:13 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:24:15 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Jan 12 09:24:26 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:24:26 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:26 volumio sudo[2294]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:24:26 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:24:28 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Jan 12 09:24:28 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 12 09:24:30 volumio volumio[893]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:24:32 volumio volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 12 09:24:32 volumio volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:24:34 volumio volumio[893]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 12 09:24:34 volumio volumio[893]: info: Retrying Login Due to Network Error Jan 12 09:24:39 volumio volumio[893]: info: CoreCommandRouter::volumioGetState Jan 12 09:24:39 volumio volumio[893]: info: CorePlayQueue::getTrack 1 Jan 12 09:24:39 volumio sudo[2337]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 12 09:24:39 volumio sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:24:40 volumio sudo[2337]: pam_unix(sudo:session): session closed for user root Jan 12 09:24:43 volumio volumio[893]: info: peppy_screensaver: Start PeppyMeter Jan 12 09:24:44 volumio volumio[893]: info: MyVolumio login type: Token Jan 12 09:24:50 volumio volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:24:50 volumio volumio[893]: Error: connect ETIMEDOUT 69.63.180.173:443 Jan 12 09:24:50 volumio volumio[893]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 12 09:24:50 volumio volumio[893]: errno: -110, Jan 12 09:24:50 volumio volumio[893]: code: 'ETIMEDOUT', Jan 12 09:24:50 volumio volumio[893]: syscall: 'connect', Jan 12 09:24:50 volumio volumio[893]: address: '69.63.180.173', Jan 12 09:24:50 volumio volumio[893]: port: 443 Jan 12 09:24:50 volumio volumio[893]: } Jan 12 09:24:50 volumio volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:24:50 volumio sudo[2396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 09:23 Jan 12 09:24:50 volumio sudo[2396]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"