-- Logs begin at Mon 2025-01-13 22:11:55 +07, end at Mon 2025-01-13 22:28:54 +07. -- Jan 13 22:27:09 mymusic volumio[1095]: info: Preload queue cleared Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::ClearQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::serviceStop Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::serviceStop Jan 13 22:27:09 mymusic volumio[1095]: info: Airplay Stop Jan 13 22:27:09 mymusic volumio[1095]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::clearPlayQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::saveQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::addQueueItems Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::addQueueItems Jan 13 22:27:09 mymusic volumio[1095]: info: Preload queue cleared Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/01. Rung La Thay Chua - Ngoc Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/01. Rung La Thay Chua - Ngoc Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/02. Tam Hon Co Don - Kieu Nga.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/02. Tam Hon Co Don - Kieu Nga.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/04. Lien Khuc 2.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/04. Lien Khuc 2.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/05. Toc May - Vy Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/05. Toc May - Vy Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::saveQueue Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:27:09 mymusic sudo[4200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2170 Jan 13 22:27:09 mymusic sudo[4200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPlay Jan 13 22:27:09 mymusic volumio[1095]: verbose: UNSET VOLATILE: Service: airplay_emulation Jan 13 22:27:09 mymusic volumio[1095]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jan 13 22:27:09 mymusic sudo[4200]: pam_unix(sudo:session): session closed for user root Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::play index 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 0 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::serviceStop Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 0 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::serviceStop Jan 13 22:27:09 mymusic volumio[1095]: info: ControllerMpd::stop Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:27:09 mymusic sudo[4204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 2170 Jan 13 22:27:09 mymusic sudo[4204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:27:09 mymusic sudo[4204]: pam_unix(sudo:session): session closed for user root Jan 13 22:27:09 mymusic volumio[1095]: info: Shairport-Sync paused with USR2 Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand stop took 16 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::play index undefined Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:27:09 mymusic volumio[1095]: info: Shairport-Sync paused with USR2 Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand stop took 1 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:27:09 mymusic volumio[1095]: info: Jan 13 22:27:09 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:27:09 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand clear took 2 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac" Jan 13 22:27:09 mymusic volumio[1095]: error: updateQueue error: null Jan 13 22:27:09 mymusic volumio[1095]: info: Jan 13 22:27:09 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:27:09 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:27:09 mymusic volumio[1095]: info: ------------------------------ 4ms Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac" took 2 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jan 13 22:27:09 mymusic volumio[1095]: info: ------------------------------ 3ms Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand play took 2 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: info: Jan 13 22:27:09 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:27:09 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:27:09 mymusic volumio[1095]: info: Jan 13 22:27:09 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:27:09 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand status took 90 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand status took 5 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:27:09 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"Tinh Yeu","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac","trackType":"flac"} Jan 13 22:27:09 mymusic volumio[1095]: verbose: CURRENT POSITION 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:27:09 mymusic volumio[1095]: info: ------------------------------ 99ms Jan 13 22:27:09 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 13 22:27:09 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:27:09 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"Tinh Yeu","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac","trackType":"flac"} Jan 13 22:27:09 mymusic volumio[1095]: verbose: CURRENT POSITION 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jan 13 22:27:09 mymusic volumio[1095]: info: Received an update from plugin. extracting info from payload Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:09 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:09 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:09 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:09 mymusic volumio[1095]: info: ------------------------------ 27ms Jan 13 22:27:10 mymusic volumio[1095]: info: Restarting Shairport-Sync after stop Jan 13 22:27:10 mymusic volumio[1095]: info: Restarting Shairport-Sync after stop Jan 13 22:27:10 mymusic sudo[4226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:27:10 mymusic sudo[4228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 22:27:10 mymusic sudo[4228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:27:10 mymusic sudo[4226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 22:27:10 mymusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 22:27:10 mymusic shairport-sync[2170]: {"time":1736781360635,"response":"startAirplayPlayback Success"} Jan 13 22:27:10 mymusic systemd[1]: shairport-sync.service: Succeeded. Jan 13 22:27:10 mymusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 22:27:10 mymusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 22:27:10 mymusic systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 22:27:10 mymusic sudo[4228]: pam_unix(sudo:session): session closed for user root Jan 13 22:27:10 mymusic systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 13 22:27:10 mymusic volumio[1095]: info: Shairport-Sync retarted Jan 13 22:27:10 mymusic systemd[1]: shairport-sync.service: Succeeded. Jan 13 22:27:10 mymusic systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 22:27:10 mymusic systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 22:27:10 mymusic sudo[4226]: pam_unix(sudo:session): session closed for user root Jan 13 22:27:10 mymusic volumio[1095]: info: Shairport-Sync retarted Jan 13 22:27:31 mymusic ntpd[1126]: Soliciting pool server 2606:4700:f1::1 Jan 13 22:27:37 mymusic ntpd[1126]: Soliciting pool server 119.28.230.190 Jan 13 22:27:42 mymusic ntpd[1126]: Soliciting pool server 119.28.230.190 Jan 13 22:27:44 mymusic volumio[1095]: info: Preload queue cleared Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::ClearQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::serviceStop Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::serviceStop Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::stop Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::clearPlayQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::saveQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::addQueueItems Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::addQueueItems Jan 13 22:27:44 mymusic volumio[1095]: info: Preload queue cleared Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/01. Rung La Thay Chua - Ngoc Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/01. Rung La Thay Chua - Ngoc Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/02. Tam Hon Co Don - Kieu Nga.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/02. Tam Hon Co Don - Kieu Nga.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::saveQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPlay Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::play index 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::addQueueItems Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::addQueueItems Jan 13 22:27:44 mymusic volumio[1095]: info: Preload queue cleared Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/04. Lien Khuc 2.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/04. Lien Khuc 2.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/05. Toc May - Vy Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/05. Toc May - Vy Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Adding Item to queue: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: Using cached record of: music-library/USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::saveQueue Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::play index undefined Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:27:44 mymusic volumio[1095]: info: Jan 13 22:27:44 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand stop took 27 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand stop took 13 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:27:44 mymusic volumio[1095]: info: Jan 13 22:27:44 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:27:44 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand clear took 2 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac" Jan 13 22:27:44 mymusic volumio[1095]: error: updateQueue error: null Jan 13 22:27:44 mymusic volumio[1095]: info: Jan 13 22:27:44 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:27:44 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:27:44 mymusic volumio[1095]: info: ------------------------------ 2ms Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac" took 1 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jan 13 22:27:44 mymusic volumio[1095]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 13 22:27:44 mymusic volumio[1095]: info: ------------------------------ 7ms Jan 13 22:27:44 mymusic volumio[1095]: info: ------------------------------ 3ms Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand play took 2 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: Jan 13 22:27:44 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:27:44 mymusic volumio[1095]: info: Jan 13 22:27:44 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":650,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lien Khuc 1&2","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac","trackType":"flac"} Jan 13 22:27:44 mymusic volumio[1095]: verbose: CURRENT POSITION 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:27:44 mymusic volumio[1095]: info: ------------------------------ 7ms Jan 13 22:27:44 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 22:27:44 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:27:44 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":650,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lien Khuc 1&2","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac","trackType":"flac"} Jan 13 22:27:44 mymusic volumio[1095]: verbose: CURRENT POSITION 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jan 13 22:27:44 mymusic volumio[1095]: info: Received an update from plugin. extracting info from payload Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:44 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:27:44 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:27:44 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:27:45 mymusic volumio[1095]: info: ------------------------------ 13ms Jan 13 22:28:06 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 22:28:06 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 22:28:08 mymusic volumio[1095]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Jan 13 22:28:10 mymusic volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioNext Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::next Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::serviceStop Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::serviceStop Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::stop Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:28:33 mymusic volumio[1095]: info: Jan 13 22:28:33 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand stop took 17 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::play index undefined Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand status took 3 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand stop took 2 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:28:33 mymusic volumio[1095]: info: Jan 13 22:28:33 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:28:33 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand clear took 1 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac" Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lien Khuc 1&2","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac","trackType":"flac"} Jan 13 22:28:33 mymusic volumio[1095]: verbose: CURRENT POSITION 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService stop Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: No code Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: ------------------------------ 29ms Jan 13 22:28:33 mymusic volumio[1095]: error: updateQueue error: null Jan 13 22:28:33 mymusic volumio[1095]: info: Jan 13 22:28:33 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:28:33 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:28:33 mymusic volumio[1095]: info: ------------------------------ 25ms Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac" took 23 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jan 13 22:28:33 mymusic volumio[1095]: info: Jan 13 22:28:33 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:33 mymusic volumio[1095]: info: ------------------------------ 9ms Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand play took 8 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:33 mymusic volumio[1095]: info: Jan 13 22:28:33 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand status took 7 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand status took 1 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"962 Kbps","isStreaming":false,"title":"Tinh Yeu","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac","trackType":"flac"} Jan 13 22:28:33 mymusic volumio[1095]: verbose: CURRENT POSITION 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:28:33 mymusic volumio[1095]: info: ------------------------------ 9ms Jan 13 22:28:33 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 22:28:33 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:33 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":169,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"962 Kbps","isStreaming":false,"title":"Tinh Yeu","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac","trackType":"flac"} Jan 13 22:28:33 mymusic volumio[1095]: verbose: CURRENT POSITION 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jan 13 22:28:33 mymusic volumio[1095]: info: Received an update from plugin. extracting info from payload Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:33 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:33 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:33 mymusic volumio[1095]: info: ------------------------------ 25ms Jan 13 22:28:36 mymusic ntpd[1126]: Soliciting pool server 2001:ac8:a:23:0:1:0:26 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioNext Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::next Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::stop Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::stPlaybackTimer Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::serviceStop Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 5 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::serviceStop Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::stop Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:28:37 mymusic volumio[1095]: info: Jan 13 22:28:37 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand stop took 7 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::play index undefined Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::startPlaybackTimer Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::updateTrackBlock Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrackBlock Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand status took 2 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand stop took 1 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Jan 13 22:28:37 mymusic volumio[1095]: info: Jan 13 22:28:37 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:28:37 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand clear took 2 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac" Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Tinh Yeu","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/06. Tinh Yeu - Ngoc Lan.flac","trackType":"flac"} Jan 13 22:28:37 mymusic volumio[1095]: verbose: CURRENT POSITION 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService stop Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: No code Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: ------------------------------ 22ms Jan 13 22:28:37 mymusic volumio[1095]: error: updateQueue error: null Jan 13 22:28:37 mymusic volumio[1095]: info: Jan 13 22:28:37 mymusic volumio[1095]: ---------------------------- MPD announces system playlist update Jan 13 22:28:37 mymusic volumio[1095]: info: Ignoring MPD Status Update Jan 13 22:28:37 mymusic volumio[1095]: info: ------------------------------ 19ms Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand add "USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac" took 18 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Jan 13 22:28:37 mymusic volumio[1095]: info: Jan 13 22:28:37 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:37 mymusic volumio[1095]: info: ------------------------------ 13ms Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand play took 13 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:37 mymusic volumio[1095]: info: Jan 13 22:28:37 mymusic volumio[1095]: ---------------------------- MPD announces state update: player Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::getState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand status took 6 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand status took 4 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseState Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":650,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"758 Kbps","isStreaming":false,"title":"Lien Khuc 1&2","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac","trackType":"flac"} Jan 13 22:28:37 mymusic volumio[1095]: verbose: CURRENT POSITION 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Jan 13 22:28:37 mymusic volumio[1095]: info: ------------------------------ 12ms Jan 13 22:28:37 mymusic volumio[1095]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 13 22:28:37 mymusic volumio[1095]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:28:37 mymusic volumio[1095]: info: ControllerMpd::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::servicePushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: verbose: STATE SERVICE {"status":"play","position":0,"seek":113,"duration":650,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"907 Kbps","isStreaming":false,"title":"Lien Khuc 1&2","artist":"Ngoc Lan","album":"Chieu 1 Minh Qua Pho(Asia 24)","uri":"USB/data/[HD] Viet Nam/ASIA CD - LOSSLESS/Asia 024 - LK Chieu Mot Minh Qua Pho/03. Lien Khuc 1&2.flac","trackType":"flac"} Jan 13 22:28:37 mymusic volumio[1095]: verbose: CURRENT POSITION 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState stateService play Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::syncState currentStatus play Jan 13 22:28:37 mymusic volumio[1095]: info: Received an update from plugin. extracting info from payload Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: CoreStateMachine::pushState Jan 13 22:28:37 mymusic volumio[1095]: info: CorePlayQueue::getTrack 2 Jan 13 22:28:37 mymusic volumio[1095]: info: CoreCommandRouter::volumioPushState Jan 13 22:28:37 mymusic volumio[1095]: info: ------------------------------ 28ms Jan 13 22:28:45 mymusic ntpd[1126]: Soliciting pool server 119.28.230.190 Jan 13 22:28:49 mymusic ntpd[1126]: Soliciting pool server 119.28.230.190 Jan 13 22:28:54 mymusic volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:28:54 mymusic volumio[1095]: Error: connect ETIMEDOUT 151.101.194.79:80 Jan 13 22:28:54 mymusic volumio[1095]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 13 22:28:54 mymusic volumio[1095]: errno: -110, Jan 13 22:28:54 mymusic volumio[1095]: code: 'ETIMEDOUT', Jan 13 22:28:54 mymusic volumio[1095]: syscall: 'connect', Jan 13 22:28:54 mymusic volumio[1095]: address: '151.101.194.79', Jan 13 22:28:54 mymusic volumio[1095]: port: 80 Jan 13 22:28:54 mymusic volumio[1095]: } Jan 13 22:28:54 mymusic volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:28:54 mymusic sudo[4470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 22:27 Jan 13 22:28:54 mymusic sudo[4470]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"