-- Logs begin at Tue 2025-12-30 21:14:21 CST, end at Tue 2025-12-30 21:25:51 CST. -- Dec 30 21:24:15 volumio sudo[2884]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl stop volumio-alsa-config Dec 30 21:24:15 volumio sudo[2884]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 30 21:24:15 volumio sudo[2884]: pam_unix(sudo:session): session closed for user root Dec 30 21:24:16 volumio sudo[2887]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl disable volumio-alsa-config Dec 30 21:24:16 volumio sudo[2887]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 30 21:24:16 volumio sudo[2887]: pam_unix(sudo:session): session closed for user root Dec 30 21:24:33 volumio sudo[2954]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/sbin/alsactl store --file /var/lib/alsa/asound.state Dec 30 21:24:33 volumio sudo[2954]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 30 21:24:33 volumio sudo[2954]: pam_unix(sudo:session): session closed for user root Dec 30 21:24:34 volumio sudo[2956]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl restart alsa-restore Dec 30 21:24:34 volumio sudo[2956]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Dec 30 21:24:34 volumio systemd[1]: Stopping Save/Restore Sound Card State... Dec 30 21:24:34 volumio systemd[1]: alsa-restore.service: Succeeded. Dec 30 21:24:34 volumio systemd[1]: Stopped Save/Restore Sound Card State. Dec 30 21:24:34 volumio systemd[1]: Starting Save/Restore Sound Card State... Dec 30 21:24:34 volumio systemd[1]: Started Save/Restore Sound Card State. Dec 30 21:24:34 volumio sudo[2956]: pam_unix(sudo:session): session closed for user root Dec 30 21:24:57 volumio ntpd[1130]: 185.209.85.222 local addr 192.168.1.215 -> Dec 30 21:25:27 volumio ntpd[1130]: 5.79.108.34 local addr 192.168.1.215 -> Dec 30 21:25:35 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::ClearQueue Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::stPlaybackTimer Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 1 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::serviceStop Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 1 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::serviceStop Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::stop Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::addQueueItems Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::addQueueItems Dec 30 21:25:35 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:35 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/281gl.flac Dec 30 21:25:35 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/281gl.flac Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPlay Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::play index 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::addQueueItems Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::addQueueItems Dec 30 21:25:35 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:35 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/nknkyldl.flac Dec 30 21:25:35 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/nknkyldl.flac Dec 30 21:25:35 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/huaxiang.flac Dec 30 21:25:35 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/huaxiang.flac Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::play index undefined Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrackBlock Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::startPlaybackTimer Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/music/281gl.flac Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand stop took 18 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand stop took 3 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand clear took 2 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/music/281gl.flac" Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: 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} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: No code Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: 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} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: No code Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 33ms Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 33ms Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: error: Upnp client error: Error: This socket has been ended by the other party Dec 30 21:25:35 volumio volumio[862]: error: updateQueue error: null Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 35ms Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand add "INTERNAL/music/281gl.flac" took 34 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 3ms Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 2ms Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand play Dec 30 21:25:35 volumio volumio[862]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 40ms Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:35 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 11ms Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand play took 9 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 8ms Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 9ms Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 6 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 7 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 5 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: info: Dec 30 21:25:35 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand clearerror took 11 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand clearerror took 10 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand clearerror took 9 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand clearerror took 9 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 44ms Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 43ms Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 43ms Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 42ms Dec 30 21:25:35 volumio volumio[862]: info: sendMpdCommand playlistinfo took 29 milliseconds Dec 30 21:25:35 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:35 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:35 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:35 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:35 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:35 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:35 volumio volumio[862]: info: ------------------------------ 44ms Dec 30 21:25:36 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::ClearQueue Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::addQueueItems Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::addQueueItems Dec 30 21:25:36 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:36 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/281gl.flac Dec 30 21:25:36 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/281gl.flac Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrackBlock Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPlay Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::play index 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::addQueueItems Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::addQueueItems Dec 30 21:25:36 volumio volumio[862]: info: Preload queue cleared Dec 30 21:25:36 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/nknkyldl.flac Dec 30 21:25:36 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/nknkyldl.flac Dec 30 21:25:36 volumio volumio[862]: info: Adding Item to queue: music-library/INTERNAL/music/huaxiang.flac Dec 30 21:25:36 volumio volumio[862]: info: Using cached record of: music-library/INTERNAL/music/huaxiang.flac Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::saveQueue Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::play index undefined Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrackBlock Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::startPlaybackTimer Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/music/281gl.flac Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand stop Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand stop took 2 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clear Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:36 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 0 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand clear took 1 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/music/281gl.flac" Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: error: updateQueue error: null Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:36 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 3ms Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand add "INTERNAL/music/281gl.flac" took 3 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 2 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand play Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: 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} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: No code Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: 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} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: No code Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 20ms Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 38ms Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 38ms Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:36 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces system playlist update Dec 30 21:25:36 volumio volumio[862]: info: Ignoring MPD Status Update Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 40ms Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand play took 39 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 3ms Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 3ms Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: info: Dec 30 21:25:36 volumio volumio[862]: ---------------------------- MPD announces state update: player Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::getState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 9 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 10 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 9 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand status took 9 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseState Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand clearerror took 10 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand clearerror took 9 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand clearerror took 9 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand clearerror took 10 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 21:25:36 volumio volumio[862]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:36 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ControllerMpd::pushState Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::servicePushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"281公里","artist":null,"album":null,"uri":"INTERNAL/music/281gl.flac","trackType":"flac"} Dec 30 21:25:36 volumio volumio[862]: verbose: CURRENT POSITION 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState stateService pause Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::syncState currentStatus stop Dec 30 21:25:36 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:36 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:36 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 45ms Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 45ms Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 45ms Dec 30 21:25:36 volumio volumio[862]: info: ------------------------------ 44ms Dec 30 21:25:40 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Dec 30 21:25:40 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: info: Retrieving Cloud Streaming UI Dec 30 21:25:42 volumio volumio[862]: info: Getting Tidal Cloud Configuration Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: info: Getting Qobuz Cloud Configuration Dec 30 21:25:42 volumio volumio[862]: info: Asking plugin for UI Config Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: info: Getting Spotify Cloud Configuration Dec 30 21:25:42 volumio volumio[862]: info: Asking plugin for UI Config Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: info: Saving Spotify Acccount Dec 30 21:25:42 volumio volumio[862]: info: Got Tidal Cloud Configuration Dec 30 21:25:42 volumio volumio[862]: info: Got it Dec 30 21:25:42 volumio volumio[862]: info: Got it Dec 30 21:25:42 volumio volumio[862]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:42 volumio volumio[862]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 21:25:42 volumio volumio[862]: info: CoreCommandRouter::volumioGetBrowseSources Dec 30 21:25:43 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 30 21:25:43 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 30 21:25:45 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Dec 30 21:25:45 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 21:25:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 21:25:50 volumio volumio[862]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 30 21:25:50 volumio volumio[862]: info: Preparing to save Alsa Options, stopping services first Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::volumioGetState Dec 30 21:25:50 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::volumioPause Dec 30 21:25:50 volumio volumio[862]: info: CoreStateMachine::pause Dec 30 21:25:50 volumio volumio[862]: info: Saving Audio Output to: {"output_device":{"value":"1,0","label":"Analog Out"}} Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 21:25:50 volumio volumio[862]: info: Found match in Cards Database: setting mixer for card Analog Out Dec 30 21:25:50 volumio volumio[862]: info: Setting mixer Master for card Analog Out Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 30 21:25:50 volumio volumio[862]: info: Updating Volume Controller Parameters: Device: 1,0 Name: Analog Out Mixer: Master Max Vol: 100 Vol Curve; linear Vol Steps: 5 Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 30 21:25:50 volumio volumio[862]: info: Disabling external Volume Control Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 30 21:25:50 volumio volumio[862]: info: Preparing to generate the ALSA configuration file Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 30 21:25:50 volumio volumio[862]: info: VolumeController:: Volume=45 Mute =false Dec 30 21:25:50 volumio volumio[862]: info: CoreStateMachine::pushState Dec 30 21:25:50 volumio volumio[862]: info: CorePlayQueue::getTrack 0 Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 30 21:25:50 volumio volumio[862]: info: CoreCommandRouter::volumioPushState Dec 30 21:25:50 volumio volumio[862]: info: Asound.conf file written Dec 30 21:25:50 volumio sudo[3166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 21:25:50 volumio sudo[3166]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 30 21:25:50 volumio sudo[3166]: pam_unix(sudo:session): session closed for user root Dec 30 21:25:50 volumio volumio[862]: /bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted Dec 30 21:25:50 volumio volumio[862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 21:25:50 volumio volumio[862]: Error: Command failed: /usr/bin/sudo /bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 30 21:25:50 volumio volumio[862]: /bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted Dec 30 21:25:50 volumio volumio[862]: at checkExecSyncError (child_process.js:790:11) Dec 30 21:25:50 volumio volumio[862]: at execSync (child_process.js:863:15) Dec 30 21:25:50 volumio volumio[862]: at /volumio/app/plugins/audio_interface/alsa_controller/index.js:2218:13 Dec 30 21:25:50 volumio volumio[862]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Dec 30 21:25:50 volumio volumio[862]: at /volumio/node_modules/graceful-fs/graceful-fs.js:61:14 Dec 30 21:25:50 volumio volumio[862]: at FSReqCallback.oncomplete (fs.js:180:23) { Dec 30 21:25:50 volumio volumio[862]: status: 1, Dec 30 21:25:50 volumio volumio[862]: signal: null, Dec 30 21:25:50 volumio volumio[862]: output: [ Dec 30 21:25:50 volumio volumio[862]: null, Dec 30 21:25:50 volumio volumio[862]: '', Dec 30 21:25:50 volumio volumio[862]: "/bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted\n" Dec 30 21:25:50 volumio volumio[862]: ], Dec 30 21:25:50 volumio volumio[862]: pid: 3165, Dec 30 21:25:50 volumio volumio[862]: stdout: '', Dec 30 21:25:50 volumio volumio[862]: stderr: "/bin/mv: cannot move '/home/volumio/.asoundrc' to '/etc/asound.conf': Operation not permitted\n" Dec 30 21:25:50 volumio volumio[862]: } Dec 30 21:25:50 volumio volumio[862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 30 21:25:51 volumio sudo[3177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-30 21:24 Dec 30 21:25:51 volumio sudo[3177]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"