-- Logs begin at Fri 2025-04-18 12:40:56 +07, end at Fri 2025-04-18 12:46:49 +07. -- Apr 18 12:45:00 volumio go-librespot[2705]: time="2025-04-18T12:45:00+07:00" level=trace msg="sent dealer ping" Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioNext Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::next Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::stop Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::stPlaybackTimer Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 0 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::serviceStop Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 0 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::serviceStop Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::stop Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand stop took 4 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::play index undefined Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::startPlaybackTimer Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::clearAddPlayTracks USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand stop took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand clear took 1 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav" Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: 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":"C? T?m Ng?y Nay","artist":"Nh?m Tik Tik Tak","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/01. Cô Tấm Ngày Nay - Nhóm Tik Tik Tak.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService stop Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: No code Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 12:45:00 volumio volumio[2525]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 16ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 14ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 14ms Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 15ms Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav" took 14 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 3ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 2ms Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand play Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:00 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 6ms Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand play took 5 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 4ms Apr 18 12:45:00 volumio go-librespot[2705]: time="2025-04-18T12:45:00+07:00" level=trace msg="received dealer pong" Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: Apr 18 12:45:00 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand status took 2 milliseconds Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:00 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:00 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 13ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 19ms Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 18 12:45:00 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:00 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:00 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":306,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:00 volumio volumio[2525]: verbose: CURRENT POSITION 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:00 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:00 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:00 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 41ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 40ms Apr 18 12:45:00 volumio volumio[2525]: info: ------------------------------ 39ms Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:00 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::volumioNext Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::next Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::stop Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::stPlaybackTimer Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::serviceStop Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 1 Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::serviceStop Apr 18 12:45:05 volumio volumio[2525]: info: ControllerMpd::stop Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:45:05 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:05 volumio volumio[2525]: info: Apr 18 12:45:05 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand stop took 4 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::play index undefined Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::startPlaybackTimer Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::clearAddPlayTracks USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:45:05 volumio volumio[2525]: info: Apr 18 12:45:05 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:05 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:05 volumio volumio[2525]: info: Apr 18 12:45:05 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:05 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand status took 3 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand stop took 2 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:05 volumio volumio[2525]: info: Apr 18 12:45:05 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:05 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand status took 2 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand clear took 2 milliseconds Apr 18 12:45:05 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav" Apr 18 12:45:05 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:05 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:05 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:05 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:05 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:05 volumio volumio[2525]: 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":"Thi?n ???ng Mong Manh","artist":"Ph??ng Thanh, Ho?ng D?ng","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/02. Thiên Đường Mong Manh - Phương Thanh, Hoàng Dũng.wav","trackType":"wav"} Apr 18 12:45:05 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService stop Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: No code Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 19ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 18ms Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:06 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:06 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:06 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:06 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 17ms Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav" took 16 milliseconds Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand play Apr 18 12:45:06 volumio volumio[2525]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 19ms Apr 18 12:45:06 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:06 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:06 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 5ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 5ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 5ms Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand play took 5 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:06 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:45:06 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 1ms Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 2 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: Apr 18 12:45:06 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:06 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:06 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 14ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 21ms Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 18 12:45:06 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:06 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:06 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:45:06 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:45:06 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:06 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:06 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 41ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 39ms Apr 18 12:45:06 volumio volumio[2525]: info: ------------------------------ 39ms Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:07 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Apr 18 12:45:09 volumio volumio[2525]: info: VolumeController::SetAlsaVolume15 Apr 18 12:45:09 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:09 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:09 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:09 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:09 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Apr 18 12:45:09 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 20 Apr 18 12:45:09 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 15 Apr 18 12:45:09 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:09 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 15 Apr 18 12:45:11 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 15 Apr 18 12:45:11 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:45:11 volumio go-librespot[2705]: time="2025-04-18T12:45:11+07:00" level=debug msg="update volume to 9830/65535" Apr 18 12:45:12 volumio volumio[2525]: info: VolumeController::SetAlsaVolume- Apr 18 12:45:12 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:12 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:12 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:12 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 15 Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 10 Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:12 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 10 Apr 18 12:45:12 volumio go-librespot[2705]: time="2025-04-18T12:45:12+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:45:12 volumio go-librespot[2705]: time="2025-04-18T12:45:12+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":15,"max":100}} Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 15 Apr 18 12:45:12 volumio volumio[2525]: info: Setting Volumio Volume from Spotify: 15 Apr 18 12:45:12 volumio volumio[2525]: info: VolumeController::SetAlsaVolume15 Apr 18 12:45:12 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:12 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:12 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:12 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:12 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Apr 18 12:45:13 volumio volumio[2525]: info: VolumeController::SetAlsaVolume- Apr 18 12:45:13 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:13 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:13 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:13 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 15 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 10 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:13 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 10 Apr 18 12:45:13 volumio volumio[2525]: info: VolumeController::SetAlsaVolume- Apr 18 12:45:13 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:13 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:13 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:13 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 10 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 5 Apr 18 12:45:13 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:13 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 5 Apr 18 12:45:14 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 5 Apr 18 12:45:14 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:45:14 volumio go-librespot[2705]: time="2025-04-18T12:45:14+07:00" level=debug msg="update volume to 3276/65535" Apr 18 12:45:14 volumio volumio[2525]: info: VolumeController::SetAlsaVolume+ Apr 18 12:45:14 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:14 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:14 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:14 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:14 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:45:14 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 5 Apr 18 12:45:14 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 10 Apr 18 12:45:14 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:14 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 10 Apr 18 12:45:15 volumio go-librespot[2705]: time="2025-04-18T12:45:15+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:45:15 volumio go-librespot[2705]: time="2025-04-18T12:45:15+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:45:15 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":5,"max":100}} Apr 18 12:45:15 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 5 Apr 18 12:45:15 volumio volumio[2525]: info: Setting Volumio Volume from Spotify: 5 Apr 18 12:45:15 volumio volumio[2525]: info: VolumeController::SetAlsaVolume5 Apr 18 12:45:15 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:15 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:15 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:15 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:15 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Apr 18 12:45:16 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Apr 18 12:45:16 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:45:16 volumio go-librespot[2705]: time="2025-04-18T12:45:16+07:00" level=debug msg="update volume to 6553/65535" Apr 18 12:45:17 volumio volumio[2525]: info: VolumeController::SetAlsaVolume+ Apr 18 12:45:17 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:45:17 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:45:17 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:45:17 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 5 Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 10 Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:45:17 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 10 Apr 18 12:45:17 volumio go-librespot[2705]: time="2025-04-18T12:45:17+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:45:17 volumio go-librespot[2705]: time="2025-04-18T12:45:17+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Apr 18 12:45:17 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Apr 18 12:45:18 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Apr 18 12:45:18 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:45:18 volumio go-librespot[2705]: time="2025-04-18T12:45:18+07:00" level=debug msg="update volume to 6553/65535" Apr 18 12:45:19 volumio go-librespot[2705]: time="2025-04-18T12:45:19+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:45:19 volumio go-librespot[2705]: time="2025-04-18T12:45:19+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:45:20 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Apr 18 12:45:20 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 18 12:45:26 volumio volumio[2525]: info: Received Get System Version Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 18 12:45:26 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Apr 18 12:45:30 volumio go-librespot[2705]: time="2025-04-18T12:45:30+07:00" level=trace msg="sent dealer ping" Apr 18 12:45:30 volumio go-librespot[2705]: time="2025-04-18T12:45:30+07:00" level=trace msg="received dealer pong" Apr 18 12:45:36 volumio ntpd[1128]: Soliciting pool server 115.165.161.155 Apr 18 12:45:39 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , setBackgrounds Apr 18 12:45:39 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Apr 18 12:45:39 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 18 12:45:43 volumio ntpd[1128]: Soliciting pool server 103.184.124.254 Apr 18 12:45:44 volumio ntpd[1128]: Soliciting pool server 2404:fbc0:0:2046::c Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 12:45:49 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 12:45:59 volumio go-librespot[2705]: time="2025-04-18T12:45:59+07:00" level=trace msg="received accesspoint ping" Apr 18 12:45:59 volumio go-librespot[2705]: time="2025-04-18T12:45:59+07:00" level=trace msg="received accesspoint pong ack" Apr 18 12:46:00 volumio go-librespot[2705]: time="2025-04-18T12:46:00+07:00" level=trace msg="sent dealer ping" Apr 18 12:46:00 volumio go-librespot[2705]: time="2025-04-18T12:46:00+07:00" level=trace msg="received dealer pong" Apr 18 12:46:00 volumio volumio[2525]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Apr 18 12:46:00 volumio sudo[3129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 12:46:00 volumio sudo[3129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:00 volumio sudo[3129]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:00 volumio volumio[2525]: info: MPD Permissions set Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:00 volumio sudo[3133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 12:46:00 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:00 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 18 12:46:00 volumio systemd[1]: mpd.service: Succeeded. Apr 18 12:46:00 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 12:46:00 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:01 volumio systemd[1]: Starting Music Player Daemon... Apr 18 12:46:01 volumio sudo[3140]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 12:46:01 volumio sudo[3140]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:01 volumio sudo[3140]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:01 volumio mpd[3144]: Apr 18 12:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 12:46:01 volumio systemd[1]: Started Music Player Daemon. Apr 18 12:46:01 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:01 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:23 volumio volumio[2525]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioGetState Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:23 volumio volumio[2525]: info: Restoring Previous Volume level: 10 false true Apr 18 12:46:23 volumio volumio[2525]: info: VolumeController::SetAlsaVolume100 Apr 18 12:46:23 volumio volumio[2525]: info: Enable softmixer device for audio device number 5 Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioStop Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::stop Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::stPlaybackTimer Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::serviceStop Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::serviceStop Apr 18 12:46:23 volumio volumio[2525]: info: ControllerMpd::stop Apr 18 12:46:23 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:46:23 volumio volumio[2525]: info: Enable softmixer device for audio device undefined Apr 18 12:46:23 volumio volumio[2525]: info: Output device has changed, restarting MPD Apr 18 12:46:23 volumio sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 12:46:23 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:23 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:23 volumio volumio[2525]: info: Output device has changed, restarting Shairport Sync Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:23 volumio sudo[3187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 12:46:23 volumio sudo[3187]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:23 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 12:46:23 volumio systemd[1]: mpd.service: Succeeded. Apr 18 12:46:23 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 12:46:23 volumio volumio[2525]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 12:46:23 volumio volumio[2525]: info: Volume configurations have been set Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 12:46:23 volumio volumio[2525]: info: Updating Volume Controller Parameters: Device: 5 Name: DAC-SQ5 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Apr 18 12:46:23 volumio systemd[1]: Starting Music Player Daemon... Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 12:46:23 volumio volumio[2525]: info: Disabling external Volume Control Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 12:46:23 volumio sudo[3192]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 12:46:23 volumio sudo[3192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:23 volumio sudo[3192]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:23 volumio volumio[2525]: error: MPD error: Error: write EPIPE Apr 18 12:46:23 volumio volumio[2525]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 18 12:46:23 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:23 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 12:46:23 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 10 Apr 18 12:46:23 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 100 Apr 18 12:46:23 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:46:23 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 100 Apr 18 12:46:23 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:23 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:23 volumio volumio[2525]: info: MPD Permissions set Apr 18 12:46:23 volumio volumio[2525]: info: Software Volume ALSA configuration written Apr 18 12:46:23 volumio volumio[2525]: info: Preparing to generate the ALSA configuration file Apr 18 12:46:23 volumio volumio[2525]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 18 12:46:23 volumio volumio[2525]: info: Reading ALSA contributions from plugins. Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 18 12:46:23 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 12:46:24 volumio volumio[2525]: info: Starting Shairport Sync Apr 18 12:46:24 volumio sudo[3215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 12:46:24 volumio sudo[3215]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 12:46:24 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 12:46:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:24 volumio sudo[3215]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:24 volumio volumio[2525]: info: Asound.conf file written Apr 18 12:46:24 volumio sudo[3221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 18 12:46:24 volumio sudo[3221]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:24 volumio sudo[3221]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:24 volumio volumio[2525]: info: Output device has changed, restarting MPD Apr 18 12:46:24 volumio mpd[3203]: Apr 18 12:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 12:46:24 volumio volumio[2525]: info: Output device has changed, restarting Shairport Sync Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:24 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:24 volumio sudo[3226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 12:46:24 volumio sudo[3226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:24 volumio sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 12:46:24 volumio sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:24 volumio sudo[3226]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:24 volumio systemd[1]: Started Music Player Daemon. Apr 18 12:46:24 volumio sudo[3187]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 12:46:24 volumio volumio[2525]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 12:46:24 volumio systemd[1]: mpd.service: Succeeded. Apr 18 12:46:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 12:46:24 volumio systemd[1]: Starting Music Player Daemon... Apr 18 12:46:24 volumio sudo[3236]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 12:46:24 volumio sudo[3236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:24 volumio sudo[3236]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:24 volumio mpd[3240]: Apr 18 12:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 12:46:24 volumio systemd[1]: Started Music Player Daemon. Apr 18 12:46:24 volumio sudo[3228]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio volumio[2525]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 18 12:46:25 volumio volumio[2525]: Found hardware: "USB-Audio" "USB Mixer" "USB262a:9023" "" "" Apr 18 12:46:25 volumio volumio[2525]: Hardware is initialized using a generic method Apr 18 12:46:25 volumio volumio[2525]: info: Output device has changed, restarting MPD Apr 18 12:46:25 volumio sudo[3247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 12:46:25 volumio sudo[3247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:25 volumio sudo[3247]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio volumio[2525]: info: Output device has changed, restarting Shairport Sync Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:25 volumio sudo[3250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 12:46:25 volumio sudo[3250]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:25 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 12:46:25 volumio systemd[1]: mpd.service: Succeeded. Apr 18 12:46:25 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 12:46:25 volumio volumio[2525]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 12:46:25 volumio systemd[1]: Starting Music Player Daemon... Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 12:46:25 volumio volumio[2525]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 12:46:25 volumio volumio[2525]: info: Disabling external Volume Control Apr 18 12:46:25 volumio sudo[3255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 12:46:25 volumio volumio[2525]: info: Shairport-Sync Started Apr 18 12:46:25 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:25 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Apr 18 12:46:25 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:46:25 volumio volumio[2525]: info: VolumeController::SetAlsaVolume0 Apr 18 12:46:25 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:25 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:25 volumio volumio[2525]: info: MPD Permissions set Apr 18 12:46:25 volumio go-librespot[2705]: time="2025-04-18T12:46:25+07:00" level=debug msg="update volume to 65535/65535" Apr 18 12:46:25 volumio volumio[2525]: info: MPD Permissions set Apr 18 12:46:25 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:25 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:25 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 18 12:46:25 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 100 Apr 18 12:46:25 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 100 Apr 18 12:46:25 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 18 12:46:25 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 12:46:25 volumio volumio[2525]: info: Starting Shairport Sync Apr 18 12:46:25 volumio volumio[2525]: info: Starting Shairport Sync Apr 18 12:46:25 volumio sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 12:46:25 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:25 volumio sudo[3284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 12:46:25 volumio sudo[3284]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 12:46:25 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 12:46:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:25 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 12:46:25 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 18 12:46:25 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 18 12:46:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:25 volumio volumio[2525]: info: Shairport-Sync Started Apr 18 12:46:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 12:46:25 volumio sudo[3284]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio volumio[2525]: info: Shairport-Sync Started Apr 18 12:46:25 volumio mpd[3266]: Apr 18 12:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 12:46:25 volumio systemd[1]: Started Music Player Daemon. Apr 18 12:46:25 volumio sudo[3250]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:25 volumio volumio[2525]: error: MPD error: The expression evaluated to a falsy value: Apr 18 12:46:25 volumio volumio[2525]: assert.ok(self.idling) Apr 18 12:46:25 volumio volumio[2525]: error: The expression evaluated to a falsy value: Apr 18 12:46:25 volumio volumio[2525]: assert.ok(self.idling) Apr 18 12:46:25 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:25 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:26 volumio go-librespot[2705]: time="2025-04-18T12:46:26+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:46:26 volumio go-librespot[2705]: time="2025-04-18T12:46:26+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:46:26 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Apr 18 12:46:26 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Apr 18 12:46:27 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 18 12:46:27 volumio sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 12:46:27 volumio sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:27 volumio systemd[1]: Stopping Music Player Daemon... Apr 18 12:46:27 volumio systemd[1]: mpd.service: Succeeded. Apr 18 12:46:27 volumio systemd[1]: Stopped Music Player Daemon. Apr 18 12:46:27 volumio systemd[1]: Starting Music Player Daemon... Apr 18 12:46:27 volumio sudo[3301]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 12:46:27 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 12:46:27 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:27 volumio mpd[3303]: Apr 18 12:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 12:46:27 volumio systemd[1]: Started Music Player Daemon. Apr 18 12:46:27 volumio sudo[3299]: pam_unix(sudo:session): session closed for user root Apr 18 12:46:27 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:28 volumio volumio[2525]: info: VolumeController::SetAlsaVolume10 Apr 18 12:46:28 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:28 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:28 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:28 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:28 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:28 volumio volumio[2525]: SPOTIFY: SPOTIFY VOLUME 100 Apr 18 12:46:28 volumio volumio[2525]: SPOTIFY: VOLUMIO VOLUME 10 Apr 18 12:46:28 volumio volumio[2525]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 18 12:46:28 volumio volumio[2525]: info: Setting Spotify Volume from Volumio: 10 Apr 18 12:46:29 volumio volumio[2525]: SPOTIFY: SETTING SPOTIFY VOLUME 10 Apr 18 12:46:29 volumio volumio[2525]: info: Sending Spotify command with payload to local API: /player/volume Apr 18 12:46:29 volumio go-librespot[2705]: time="2025-04-18T12:46:29+07:00" level=debug msg="update volume to 6553/65535" Apr 18 12:46:30 volumio go-librespot[2705]: time="2025-04-18T12:46:30+07:00" level=trace msg="sent dealer ping" Apr 18 12:46:30 volumio go-librespot[2705]: time="2025-04-18T12:46:30+07:00" level=trace msg="received dealer pong" Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::volumioPlay Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::play index undefined Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::startPlaybackTimer Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::clearAddPlayTracks USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand stop took 1 milliseconds Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 12:46:30 volumio volumio[2525]: info: Apr 18 12:46:30 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:46:30 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand clear took 0 milliseconds Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav" Apr 18 12:46:30 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:30 volumio volumio[2525]: info: Apr 18 12:46:30 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:46:30 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:46:30 volumio volumio[2525]: info: ------------------------------ 1ms Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav" took 0 milliseconds Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand play Apr 18 12:46:30 volumio volumio[2525]: info: ------------------------------ 1ms Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand play took 1 milliseconds Apr 18 12:46:30 volumio volumio[2525]: info: Apr 18 12:46:30 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:46:30 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:46:30 volumio volumio[2525]: info: Apr 18 12:46:30 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand status took 5 milliseconds Apr 18 12:46:30 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:46:30 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:46:30 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:46:30 volumio volumio[2525]: info: ------------------------------ 12ms Apr 18 12:46:30 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:46:30 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:46:30 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":377,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:46:30 volumio volumio[2525]: verbose: CURRENT POSITION 2 Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:46:30 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:30 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:30 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:30 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:30 volumio volumio[2525]: info: ------------------------------ 12ms Apr 18 12:46:30 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:30 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:30 volumio go-librespot[2705]: time="2025-04-18T12:46:30+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Apr 18 12:46:30 volumio go-librespot[2705]: time="2025-04-18T12:46:30+07:00" level=trace msg="emitting websocket event: volume" Apr 18 12:46:30 volumio volumio[2525]: SPOTIFY: received: {"type":"volume","data":{"value":10,"max":100}} Apr 18 12:46:30 volumio volumio[2525]: SPOTIFY: RECEIVED SPOTIFY VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioNext Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::next Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::stop Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::stPlaybackTimer Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::serviceStop Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 2 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::serviceStop Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::stop Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: info: Apr 18 12:46:37 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand stop took 5 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::play index undefined Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::startPlaybackTimer Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::clearAddPlayTracks USB/ssd/Mong Ước Kỷ Niệm Xưa/04. Mưa Trong Mắt Em - Bảo Phúc.wav Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand stop Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::updateTrackBlock Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrackBlock Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand status took 2 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand stop took 1 milliseconds Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand clear Apr 18 12:46:37 volumio volumio[2525]: info: Apr 18 12:46:37 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:46:37 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand clear took 1 milliseconds Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/04. Mưa Trong Mắt Em - Bảo Phúc.wav" Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: 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":"L?i V?","artist":"Tr?n Thu H?","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/03. Lối Về - Trần Thu Hà.wav","trackType":"wav"} Apr 18 12:46:37 volumio volumio[2525]: verbose: CURRENT POSITION 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState stateService stop Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: No code Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: ------------------------------ 15ms Apr 18 12:46:37 volumio volumio[2525]: error: updateQueue error: null Apr 18 12:46:37 volumio volumio[2525]: info: Apr 18 12:46:37 volumio volumio[2525]: ---------------------------- MPD announces system playlist update Apr 18 12:46:37 volumio volumio[2525]: info: Ignoring MPD Status Update Apr 18 12:46:37 volumio volumio[2525]: info: ------------------------------ 13ms Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand add "USB/ssd/Mong Ước Kỷ Niệm Xưa/04. Mưa Trong Mắt Em - Bảo Phúc.wav" took 12 milliseconds Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand play Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: info: ------------------------------ 4ms Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand play took 3 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: Apr 18 12:46:37 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:46:37 volumio volumio[2525]: info: Apr 18 12:46:37 volumio volumio[2525]: ---------------------------- MPD announces state update: player Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand status took 4 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::getState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand status Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand status took 1 milliseconds Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseState Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"M?a Trong M?t Em","artist":"B?o Ph?c","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/04. Mưa Trong Mắt Em - Bảo Phúc.wav","trackType":"wav"} Apr 18 12:46:37 volumio volumio[2525]: verbose: CURRENT POSITION 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus stop Apr 18 12:46:37 volumio volumio[2525]: info: ------------------------------ 6ms Apr 18 12:46:37 volumio volumio[2525]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 18 12:46:37 volumio volumio[2525]: verbose: ControllerMpd::parseTrackInfo Apr 18 12:46:37 volumio volumio[2525]: info: ControllerMpd::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::servicePushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":319,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"M?a Trong M?t Em","artist":"B?o Ph?c","album":"Nh?ng Ca Kh?c Phim ???c Y?u Th?ch - Mong ??c K? Ni?m X?a","uri":"USB/ssd/Mong Ước Kỷ Niệm Xưa/04. Mưa Trong Mắt Em - Bảo Phúc.wav","trackType":"wav"} Apr 18 12:46:37 volumio volumio[2525]: verbose: CURRENT POSITION 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState stateService play Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::syncState currentStatus play Apr 18 12:46:37 volumio volumio[2525]: info: Received an update from plugin. extracting info from payload Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: CoreStateMachine::pushState Apr 18 12:46:37 volumio volumio[2525]: info: CorePlayQueue::getTrack 3 Apr 18 12:46:37 volumio volumio[2525]: info: CoreCommandRouter::volumioPushState Apr 18 12:46:37 volumio volumio[2525]: info: ------------------------------ 12ms Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:37 volumio volumio[2525]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Apr 18 12:46:43 volumio ntpd[1128]: Soliciting pool server 103.184.124.254 Apr 18 12:46:49 volumio volumio[2525]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 12:46:49 volumio volumio[2525]: Error: connect ETIMEDOUT 151.101.78.79:443 Apr 18 12:46:49 volumio volumio[2525]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 18 12:46:49 volumio volumio[2525]: errno: -110, Apr 18 12:46:49 volumio volumio[2525]: code: 'ETIMEDOUT', Apr 18 12:46:49 volumio volumio[2525]: syscall: 'connect', Apr 18 12:46:49 volumio volumio[2525]: address: '151.101.78.79', Apr 18 12:46:49 volumio volumio[2525]: port: 443 Apr 18 12:46:49 volumio volumio[2525]: } Apr 18 12:46:49 volumio volumio[2525]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 12:46:49 volumio sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 12:45 Apr 18 12:46:49 volumio sudo[3364]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"