-- Logs begin at Sun 2024-03-31 05:06:50 UTC, end at Sat 2024-04-06 10:52:10 UTC. -- Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::ClearQueue Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:02 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::serviceStop Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::serviceStop Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::stop Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::clearPlayQueue Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::addQueueItems Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::addQueueItems Apr 06 10:51:02 opera volumio[12884]: info: Adding Item to queue: albums://%E8%94%A1%E7%90%B4/%E9%87%91%E5%A3%B0%E5%9B%9E%E5%BF%86%E5%BD%95101%20Vol.3%20CD5 Apr 06 10:51:02 opera volumio[12884]: info: Exploding uri albums://%E8%94%A1%E7%90%B4/%E9%87%91%E5%A3%B0%E5%9B%9E%E5%BF%86%E5%BD%95101%20Vol.3%20CD5 in service mpd Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPlay Apr 06 10:51:02 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::play index 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:02 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::play index undefined Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RTT2DVE/蔡琴 - 金聲回憶錄101 Vol.3 CD5 WAV/01 蔡琴 - 恰似你的溫柔.wav Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:02 opera volumio[12884]: info: Apr 06 10:51:02 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand stop took 34 milliseconds Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand stop took 10 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:51:02 opera volumio[12884]: info: Apr 06 10:51:02 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:02 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand clear took 3 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RTT2DVE/蔡琴 - 金聲回憶錄101 Vol.3 CD5 WAV/01 蔡琴 - 恰似你的溫柔.wav" Apr 06 10:51:02 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:02 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:02 opera volumio[12884]: error: updateQueue error: null Apr 06 10:51:02 opera volumio[12884]: info: Apr 06 10:51:02 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:02 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:02 opera volumio[12884]: info: ------------------------------ 14ms Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RTT2DVE/蔡琴 - 金聲回憶錄101 Vol.3 CD5 WAV/01 蔡琴 - 恰似你的溫柔.wav" took 13 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:51:02 opera volumio[12884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:51:02 opera volumio[12884]: info: ------------------------------ 19ms Apr 06 10:51:02 opera volumio[12884]: info: ------------------------------ 6ms Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand play took 4 milliseconds Apr 06 10:51:02 opera volumio[12884]: info: Apr 06 10:51:02 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:02 opera volumio[12884]: info: Apr 06 10:51:02 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 蔡琴 - 恰似你的溫柔.wav","artist":"蔡琴","album":"金声回忆录101 Vol.3 CD5","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RTT2DVE/蔡琴 - 金聲回憶錄101 Vol.3 CD5 WAV/01 蔡琴 - 恰似你的溫柔.wav","trackType":"wav"} Apr 06 10:51:02 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:02 opera volumio[12884]: info: ------------------------------ 19ms Apr 06 10:51:02 opera volumio[12884]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 06 10:51:02 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:02 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":242,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 蔡琴 - 恰似你的溫柔.wav","artist":"蔡琴","album":"金声回忆录101 Vol.3 CD5","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RTT2DVE/蔡琴 - 金聲回憶錄101 Vol.3 CD5 WAV/01 蔡琴 - 恰似你的溫柔.wav","trackType":"wav"} Apr 06 10:51:02 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:51:02 opera volumio[12884]: info: Received an update from plugin. extracting info from payload Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:02 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:02 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:02 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:02 opera volumio[12884]: info: ------------------------------ 30ms Apr 06 10:51:02 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:02 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:02 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::ClearQueue Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:06 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::serviceStop Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::serviceStop Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::stop Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::clearPlayQueue Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::addQueueItems Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::addQueueItems Apr 06 10:51:06 opera volumio[12884]: info: Adding Item to queue: albums://%E9%84%A7%E9%BA%97%E5%90%9B/Teresa%20Teng%20Top%20Ten%20-%20Japan%20Taurus%2030TX-1076 Apr 06 10:51:06 opera volumio[12884]: info: Exploding uri albums://%E9%84%A7%E9%BA%97%E5%90%9B/Teresa%20Teng%20Top%20Ten%20-%20Japan%20Taurus%2030TX-1076 in service mpd Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPlay Apr 06 10:51:06 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::play index 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:06 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::play index undefined Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RUWB650/01 つぐない.flac Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:06 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:06 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:06 opera volumio[12884]: info: Apr 06 10:51:06 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand stop took 59 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand stop took 33 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand status took 1 milliseconds Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:06 opera volumio[12884]: info: Apr 06 10:51:06 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:06 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand clear took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RUWB650/01 つぐない.flac" Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:06 opera volumio[12884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:51:06 opera volumio[12884]: info: ------------------------------ 7ms Apr 06 10:51:06 opera volumio[12884]: error: updateQueue error: null Apr 06 10:51:06 opera volumio[12884]: info: Apr 06 10:51:06 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:06 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:06 opera volumio[12884]: info: ------------------------------ 4ms Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RUWB650/01 つぐない.flac" took 3 milliseconds Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:51:06 opera volumio[12884]: info: ------------------------------ 3ms Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand play took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: Apr 06 10:51:06 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:06 opera volumio[12884]: info: Apr 06 10:51:06 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"つぐない","artist":"鄧麗君","album":"Teresa Teng Top Ten - Japan Taurus 30TX-1076","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RUWB650/01 つぐない.flac","trackType":"flac"} Apr 06 10:51:06 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:06 opera volumio[12884]: info: ------------------------------ 17ms Apr 06 10:51:06 opera volumio[12884]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 06 10:51:06 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:06 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"592 Kbps","isStreaming":false,"title":"つぐない","artist":"鄧麗君","album":"Teresa Teng Top Ten - Japan Taurus 30TX-1076","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RUWB650/01 つぐない.flac","trackType":"flac"} Apr 06 10:51:06 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:51:06 opera volumio[12884]: info: Received an update from plugin. extracting info from payload Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:06 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:06 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:06 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:06 opera volumio[12884]: info: ------------------------------ 32ms Apr 06 10:51:06 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:06 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:06 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::ClearQueue Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:12 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::serviceStop Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::serviceStop Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::stop Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::clearPlayQueue Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::addQueueItems Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::addQueueItems Apr 06 10:51:12 opera volumio[12884]: info: Adding Item to queue: albums://%E6%9E%97%E6%86%B6%E8%93%AE/%E7%B2%BE%E9%81%B816%E9%A6%96%20SACD%20Best%20Collection%20 Apr 06 10:51:12 opera volumio[12884]: info: Exploding uri albums://%E6%9E%97%E6%86%B6%E8%93%AE/%E7%B2%BE%E9%81%B816%E9%A6%96%20SACD%20Best%20Collection%20 in service mpd Apr 06 10:51:12 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:12 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::saveQueue Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPlay Apr 06 10:51:12 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::play index 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:12 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::play index undefined Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/01 - 難忘您.flac Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand stop took 101 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: Apr 06 10:51:12 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand stop took 63 milliseconds Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:51:12 opera volumio[12884]: info: Apr 06 10:51:12 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:12 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand clear took 3 milliseconds Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/01 - 難忘您.flac" Apr 06 10:51:12 opera volumio[12884]: error: updateQueue error: null Apr 06 10:51:12 opera volumio[12884]: info: Apr 06 10:51:12 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:12 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:12 opera volumio[12884]: info: ------------------------------ 7ms Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/01 - 難忘您.flac" took 4 milliseconds Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:51:12 opera volumio[12884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:51:12 opera volumio[12884]: info: ------------------------------ 13ms Apr 06 10:51:12 opera volumio[12884]: info: ------------------------------ 5ms Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand play took 3 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: Apr 06 10:51:12 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:12 opera volumio[12884]: info: Apr 06 10:51:12 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand status took 28 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - 難忘您","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/01 - 難忘您.flac","trackType":"flac"} Apr 06 10:51:12 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:12 opera volumio[12884]: info: ------------------------------ 50ms Apr 06 10:51:12 opera volumio[12884]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 06 10:51:12 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:12 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":254,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"360 Kbps","isStreaming":false,"title":"01 - 難忘您","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/01 - 難忘您.flac","trackType":"flac"} Apr 06 10:51:12 opera volumio[12884]: verbose: CURRENT POSITION 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:51:12 opera volumio[12884]: info: Received an update from plugin. extracting info from payload Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:12 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:12 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:12 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:12 opera volumio[12884]: info: ------------------------------ 42ms Apr 06 10:51:12 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:12 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:12 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:22 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Pink%20Floyd/Wish%20You%20Were%20Here%20-%20Immersion%20Edition%20Bluray/ec12fba6-7093-4e39-80d7-7ef6b2ee396f.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beatles/Abbey%20Road%20(24%20BIT%20Remastered)/f6ede1bc-2800-4208-86cf-dec2c1a1f5cb.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Yes/Almost%20Famous/062ea8ad-06ff-4d56-b16b-8fe98c5fdc6f.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Radiohead/Amnesiac/fb6b5407-8527-4d0b-9f60-1222dcf36735.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michelle%20Shocked/Dead%20Man%20Walking/a914f2aa-184a-43ef-a50d-10efb583bb64.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Who/Endless%20Wire/ad7c6773-6df4-4e1e-beb2-18b0ed092a24.png' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Shirley%20Horn/Here%27s%20To%20Life/1e005cd7-a5bf-47e2-aa9b-614fb5955417.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Shirley%20Horn/I%20Remember%20Miles/73b404f6-d9f3-49d5-ae3f-1e8d3df0a237.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Radiohead/In%20Rainbows/bc0cb21e-15b1-442a-a451-c1cf305aea24.png' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Who/It%27s%20Hard/7b12121d-f650-482d-b500-d0c8d58819c9.png' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jimi%20Hendrix/Jimi%20Blues/4b918fa6-e29b-40b1-8067-7ca7cc1f6dec.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20New%20Basement%20Tapes/Lost%20On%20The%20River%20(Deluxe)/cce0fe5b-eab9-415e-ae19-63ba2f7c91b5.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dialogue/Napoleon%20Dynamite/56720c99-bad0-4a1e-ae42-3c87792c25d6.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Manu%20Katch%C3%A9/Neighbourhood/8bc8762d-8e37-4a25-bdbb-3fcc66168244.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Leonard%20Cohen/Old%20Ideas/7b510800-1be1-4ea8-99ec-2449990570e6.png' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Rickie%20Lee%20Jones/Pop%20Pop/ce62c234-6c5b-485b-b688-09b25207600e.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Dusty%20Springfield/Pulp%20Fiction/014533bf-4254-4598-84c5-62757137fefb.png' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Beatles/Revolver%20(24%20BIT%20Remastered)/873b07c7-3ed0-48ae-9b7c-de126802dba2.jpg' Apr 06 10:51:23 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Fairfield%20Four/Standing%20In%20The%20Safety%20Zone/7d8c5f10-0133-4969-8e2d-28c43fdd2e1d.jpg' Apr 06 10:51:24 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sarah%20McLachlan/Surfacing/d598de60-57b4-499d-b442-2fe6a7a9b68e.png' Apr 06 10:51:24 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eminem/The%20Eminem%20Show/656b100a-9725-47a1-8aaf-59a6c71ba023.jpg' Apr 06 10:51:24 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Annie%20Lennox/The%20Unplugged%20Collection%20Volume%20One/4225a9fb-287e-4ec2-a7bd-84375ce1a1aa.jpg' Apr 06 10:51:24 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Pink%20Floyd/Wish%20You%20Were%20Here/a96fdfc4-a543-441d-8a82-d0e543dad976.png' Apr 06 10:51:24 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Shirley%20Horn/You%20Won%27t%20Forget%20Me/dade835d-eec7-43a3-baf1-884787dec944.jpg' Apr 06 10:51:25 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hiromi/Alive/2d160b23-0887-4d49-817e-271b27954ee2.jpg' Apr 06 10:51:25 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joni%20Mitchell/Both%20Sides%20Now/9ca6c430-dbbe-473c-97bf-d4de2dcad4ac.jpg' Apr 06 10:51:25 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Paul%20Simon/Graceland%20(25th%20Anniversary%20Edition)/18647036-3832-49dd-9a06-816648229efe.jpg' Apr 06 10:51:25 opera volumio[12884]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Peter%20Gabriel/So%20(Remastered%202012)/e60dc855-99ba-427c-863b-850d00ff7603.jpg' Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::volumioPlay Apr 06 10:51:27 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::play index 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::stop Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:27 opera volumio[12884]: verbose: UNSET VOLATILE Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::updateTrackBlock Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrackBlock Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::serviceStop Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::serviceStop Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::stop Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:27 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand stop took 51 milliseconds Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::play index undefined Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/02 - 三更半夜.flac Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:51:27 opera volumio[12884]: info: Apr 06 10:51:27 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand stop took 4 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:51:27 opera volumio[12884]: info: Apr 06 10:51:27 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:27 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand clear took 4 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/02 - 三更半夜.flac" Apr 06 10:51:27 opera volumio[12884]: error: updateQueue error: null Apr 06 10:51:27 opera volumio[12884]: info: Apr 06 10:51:27 opera volumio[12884]: ---------------------------- MPD announces system playlist update Apr 06 10:51:27 opera volumio[12884]: info: Ignoring MPD Status Update Apr 06 10:51:27 opera volumio[12884]: info: ------------------------------ 4ms Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/02 - 三更半夜.flac" took 2 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:51:27 opera volumio[12884]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:51:27 opera volumio[12884]: info: ------------------------------ 11ms Apr 06 10:51:27 opera volumio[12884]: info: ------------------------------ 5ms Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand play took 2 milliseconds Apr 06 10:51:27 opera volumio[12884]: info: Apr 06 10:51:27 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:27 opera volumio[12884]: info: Apr 06 10:51:27 opera volumio[12884]: ---------------------------- MPD announces state update: player Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::getState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseState Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":250,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3426 Kbps","isStreaming":false,"title":"02 - 三更半夜","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/02 - 三更半夜.flac","trackType":"flac"} Apr 06 10:51:27 opera volumio[12884]: verbose: CURRENT POSITION 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:27 opera volumio[12884]: info: ------------------------------ 26ms Apr 06 10:51:27 opera volumio[12884]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 06 10:51:27 opera volumio[12884]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:51:27 opera volumio[12884]: info: ControllerMpd::pushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::servicePushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":250,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3426 Kbps","isStreaming":false,"title":"02 - 三更半夜","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/02 - 三更半夜.flac","trackType":"flac"} Apr 06 10:51:27 opera volumio[12884]: verbose: CURRENT POSITION 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::syncState stateService play Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:51:27 opera volumio[12884]: info: Received an update from plugin. extracting info from payload Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:27 opera volumio[12884]: info: CoreStateMachine::pushState Apr 06 10:51:27 opera volumio[12884]: info: CorePlayQueue::getTrack 1 Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:27 opera volumio[12884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:27 opera volumio[12884]: info: ------------------------------ 34ms Apr 06 10:51:27 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:27 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:27 opera volumio[12884]: info: Updating RAAT Signal Path Apr 06 10:51:28 opera volumio[12884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:51:28 opera volumio[12884]: { Error: connect ETIMEDOUT 69.171.229.73:443 Apr 06 10:51:28 opera volumio[12884]: at Object._errnoException (util.js:1022:11) Apr 06 10:51:28 opera volumio[12884]: at _exceptionWithHostPort (util.js:1044:20) Apr 06 10:51:28 opera volumio[12884]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 06 10:51:28 opera volumio[12884]: code: 'ETIMEDOUT', Apr 06 10:51:28 opera volumio[12884]: errno: 'ETIMEDOUT', Apr 06 10:51:28 opera volumio[12884]: syscall: 'connect', Apr 06 10:51:28 opera volumio[12884]: address: '69.171.229.73', Apr 06 10:51:28 opera volumio[12884]: port: 443 } Apr 06 10:51:28 opera volumio[12884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:51:29 opera sudo[13573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 10:50 Apr 06 10:51:29 opera sudo[13573]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:29 opera sudo[13573]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:35 opera volumio-remote-updater[689]: [2024-04-06 10:51:35] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 06 10:51:35 opera volumio-remote-updater[689]: [2024-04-06 10:51:35] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 06 10:51:35 opera systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Apr 06 10:51:35 opera systemd[1]: Unit volumio.service entered failed state. Apr 06 10:51:35 opera systemd[1]: Starting dynamicswap service... Apr 06 10:51:35 opera systemd[1]: Started dynamicswap service. Apr 06 10:51:35 opera systemd[1]: volumio.service holdoff time over, scheduling restart. Apr 06 10:51:35 opera systemd[1]: Starting dynamicswap service... Apr 06 10:51:35 opera systemd[1]: Started dynamicswap service. Apr 06 10:51:35 opera systemd[1]: Stopping Volumio Backend Module... Apr 06 10:51:35 opera systemd[1]: Starting Volumio Backend Module... Apr 06 10:51:35 opera systemd[1]: Started Volumio Backend Module. Apr 06 10:51:36 opera volumio[13601]: info: ------------------------------------------- Apr 06 10:51:36 opera volumio[13601]: info: ----- Volumio2 ---- Apr 06 10:51:36 opera volumio[13601]: info: ------------------------------------------- Apr 06 10:51:36 opera volumio[13601]: info: ----- System startup ---- Apr 06 10:51:36 opera volumio[13601]: info: ------------------------------------------- Apr 06 10:51:36 opera volumio[13601]: info: Plugin folders cleanup Apr 06 10:51:36 opera volumio[13601]: info: Scanning into folder /volumio/app/plugins/ Apr 06 10:51:36 opera volumio[13601]: info: Scanning category audio_interface Apr 06 10:51:36 opera volumio[13601]: info: Scanning category miscellanea Apr 06 10:51:36 opera volumio[13601]: info: Scanning category music_service Apr 06 10:51:36 opera volumio[13601]: info: Scanning category plugins.json Apr 06 10:51:36 opera volumio[13601]: info: Scanning category system_controller Apr 06 10:51:36 opera volumio[13601]: info: Scanning category user_interface Apr 06 10:51:36 opera volumio[13601]: info: Scanning into folder /data/plugins/ Apr 06 10:51:36 opera volumio[13601]: info: Plugin folders cleanup completed Apr 06 10:51:36 opera volumio[13601]: info: ------------------------------------------- Apr 06 10:51:36 opera volumio[13601]: info: ----- Core plugins startup ---- Apr 06 10:51:36 opera volumio[13601]: info: ------------------------------------------- Apr 06 10:51:36 opera volumio[13601]: info: Loading plugins from folder /volumio/app/plugins/ Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin bluetooth to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin upnp to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin metavolumio to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin cd_controller to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin raat to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Adding plugin volspotconnect2 to MyMusic Plugins Apr 06 10:51:36 opera volumio[13601]: info: Loading plugins from folder /data/plugins/ Apr 06 10:51:36 opera volumio[13601]: info: Loading plugin "system"... Apr 06 10:51:36 opera volumio[13601]: info: Loading plugin "appearance"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "network"... Apr 06 10:51:37 opera volumio[13601]: info: Refreshing Cached IP Addresses Apr 06 10:51:37 opera sudo[13626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "services"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "alsa_controller"... Apr 06 10:51:37 opera sudo[13626]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:37 opera sudo[13629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 10:51:37 opera sudo[13626]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:37 opera sudo[13629]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:37 opera sudo[13629]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "wizard"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "volumio_command_line_client"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "upnp"... Apr 06 10:51:37 opera volumio[13601]: info: [1712400697690] Starting Upmpd Daemon Apr 06 10:51:37 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "my_music"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "mpd"... Apr 06 10:51:37 opera volumio[13601]: info: Loading plugin "upnp_browser"... Apr 06 10:51:38 opera volumio[13601]: info: Loading plugin "networkfs"... Apr 06 10:51:38 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 06 10:51:38 opera volumio[13601]: info: Loading plugin "bluetooth"... Apr 06 10:51:38 opera volumio[13601]: info: [1712400698322] Starting BluetoothController Apr 06 10:51:38 opera volumio[13601]: info: Loading plugin "alarm-clock"... Apr 06 10:51:38 opera volumio[13601]: info: Loading plugin "metavolumio"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "airplay_emulation"... Apr 06 10:51:39 opera volumio[13601]: info: Starting Shairport Sync Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "cd_controller"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "last_100"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "raat"... Apr 06 10:51:39 opera volumio[13601]: info: RAAT Plugin loaded Apr 06 10:51:39 opera volumio[13601]: info: Adding restartRAATSocket REST API Endpoint Apr 06 10:51:39 opera volumio[13601]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "streaming_services"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "webradio"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "i2s_dacs"... Apr 06 10:51:39 opera volumio[13601]: info: I2S DAC not set, start Auto-detection Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "volumiodiscovery"... Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 06 10:51:39 opera node[13601]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 06 10:51:39 opera node[13601]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** For more information see Apr 06 10:51:39 opera node[13601]: *** WARNING *** For more information see Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 06 10:51:39 opera node[13601]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 06 10:51:39 opera node[13601]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 06 10:51:39 opera volumio[13601]: *** WARNING *** For more information see Apr 06 10:51:39 opera node[13601]: *** WARNING *** For more information see Apr 06 10:51:39 opera volumio[13601]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 06 10:51:39 opera volumio[13601]: Discovery: StartAdv! undefined Apr 06 10:51:39 opera volumio[13601]: Discovery: Started advertising... Opera-3 - undefined Apr 06 10:51:39 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "outputs"... Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "albumart"... Apr 06 10:51:39 opera volumio[13601]: info: Plugin example_plugin is not enabled Apr 06 10:51:39 opera volumio[13601]: info: Loading plugin "hi_res_audio"... Apr 06 10:51:39 opera volumio[13601]: Forking 3 albumart workers Apr 06 10:51:40 opera volumio-remote-updater[689]: [2024-04-06 10:51:40] [connect] Successful connection Apr 06 10:51:40 opera volumio[13601]: Starting albumart workers Apr 06 10:51:40 opera volumio[13601]: Starting albumart workers Apr 06 10:51:40 opera volumio[13601]: Starting albumart workers Apr 06 10:51:40 opera volumio[13601]: info: Applying required configuration parameters for plugin hi_res_audio Apr 06 10:51:40 opera volumio[13601]: info: Loading plugin "inputs"... Apr 06 10:51:40 opera volumio[13601]: info: Loading plugin "qobuz"... Apr 06 10:51:41 opera volumio[13601]: info: Loading plugin "tidal"... Apr 06 10:51:42 opera volumio[13601]: info: Loading plugin "volspotconnect2"... Apr 06 10:51:42 opera volumio[13601]: info: Loading plugin "updater_comm"... Apr 06 10:51:42 opera volumio[13601]: info: Plugin mpdemulation is not enabled Apr 06 10:51:42 opera volumio[13601]: info: Loading plugin "rest_api"... Apr 06 10:51:42 opera volumio[13601]: info: Loading plugin "websocket"... Apr 06 10:51:42 opera volumio[13601]: info: ___________ START PLUGINS ___________ Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 10:51:42 opera volumio[13601]: info: [1712400702782] CoreMusicLibrary::Adding element Media Servers Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 06 10:51:42 opera volumio[13601]: info: Adding METAVOLUMIO REST API Endpoints Apr 06 10:51:42 opera volumio[13601]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 06 10:51:42 opera sudo[13703]: pam_unix(sudo:auth): conversation failed Apr 06 10:51:42 opera sudo[13703]: pam_unix(sudo:auth): auth could not identify password for [volumio] Apr 06 10:51:42 opera volumio[13601]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 10:51:42 opera volumio[13601]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 10:51:42 opera volumio[13601]: info: Preparing CD Folders Apr 06 10:51:42 opera volumio[13601]: info: Adding CD REST API Endpoints Apr 06 10:51:42 opera volumio[13601]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Apr 06 10:51:42 opera volumio[13601]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Apr 06 10:51:42 opera volumio[13601]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Apr 06 10:51:42 opera volumio[13601]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 06 10:51:42 opera volumio[13601]: info: Detecting CD presence with Flag file Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 10:51:42 opera volumio[13601]: info: [1712400702894] CoreMusicLibrary::Adding element Last_100 Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 10:51:42 opera volumio[13601]: info: Starting RAAT Plugin Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 06 10:51:42 opera volumio[13601]: info: Additional UI Settings Added for plugin music_service/raat Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 10:51:42 opera volumio[13601]: info: Streaming services startup Apr 06 10:51:42 opera volumio[13601]: info: Starting Streaming Daemon Apr 06 10:51:42 opera sudo[13718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 06 10:51:42 opera sudo[13718]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:42 opera systemd[1]: Stopping Volumio Streaming Daemon... Apr 06 10:51:42 opera systemd[1]: Starting Volumio Streaming Daemon... Apr 06 10:51:42 opera systemd[1]: Started Volumio Streaming Daemon. Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 10:51:42 opera volumio[13601]: info: [1712400702971] CoreMusicLibrary::Adding element Webradio Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 10:51:42 opera sudo[13718]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:42 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 10:51:42 opera volumio[13601]: error: Hi Res Audio Failed Login: Missing Login Data Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: ############################ Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: # Volumio Streaming Daemon # Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: # Running on port 7777 # Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: ############################ Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: Checking variant in static cache Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: Enabled services: Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: 1) tidal Apr 06 10:51:42 opera volumio-streaming-daemon[13728]: 2) qobuz Apr 06 10:51:43 opera volumio[13601]: info: Loading i18n strings for locale zh Apr 06 10:51:43 opera volumio[13601]: Updating browse sources language Apr 06 10:51:43 opera volumio[13601]: Cannot find translation for sourceMedia Servers Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 10:51:43 opera sudo[13740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 06 10:51:43 opera sudo[13740]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::initPlayerControls Apr 06 10:51:43 opera systemd[1]: Started Volspotconnect2 Daemon. Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 06 10:51:43 opera sudo[13740]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: BOOT COMPLETED Apr 06 10:51:43 opera volumio[13601]: [Metrics] CommandRouter: 6s 646.43ms Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 10:51:43 opera volumio[13601]: aplay: main:722: audio open error: Device or resource busy Apr 06 10:51:43 opera volumio[13601]: Cannot play startup sound Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::Close All Modals sent Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::Close All Modals sent Apr 06 10:51:43 opera volumio[13601]: Express server listening on port 3000 Apr 06 10:51:43 opera volumio[13601]: [Metrics] WebUI: 7s 15.34ms Apr 06 10:51:43 opera volumio[13601]: Volumio Calling Home Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::resetVolumioState Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::getcurrentVolume Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioRetrievevolume Apr 06 10:51:43 opera volumio[13601]: info: Stopping AccessToken refresher cron for QOBUZ Apr 06 10:51:43 opera volumio[13601]: info: AccessToken refresher cron started for QOBUZ Apr 06 10:51:43 opera volumio[13601]: info: Stopping AccessToken refresher cron Apr 06 10:51:43 opera volumio[13601]: info: AccessToken refresher cron started Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:43 opera volumio[13601]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 06 10:51:43 opera volumio[13601]: info: Setting Device type: Tinkerboard Apr 06 10:51:43 opera volumio[13601]: [SpotifyConnect] Vollibrespot Daemon Started Apr 06 10:51:43 opera volumio[13601]: [SpotifyConnect] Starting metadata listener Apr 06 10:51:43 opera volumio[13601]: [SpotifyConnect] FE => [ 1, [length]: 1 ] Apr 06 10:51:43 opera volumio[13601]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Opera-3' Apr 06 10:51:43 opera volumio[13601]: We trust you have received the usual lecture from the local System Apr 06 10:51:43 opera volumio[13601]: Administrator. It usually boils down to these three things: Apr 06 10:51:43 opera volumio[13601]: #1) Respect the privacy of others. Apr 06 10:51:43 opera volumio[13601]: #2) Think before you type. Apr 06 10:51:43 opera volumio[13601]: #3) With great power comes great responsibility. Apr 06 10:51:43 opera volumio[13601]: sudo: no tty present and no askpass program specified Apr 06 10:51:43 opera volumio[13601]: info: MPD running with PID723 Apr 06 10:51:43 opera volumio[13601]: ,establishing connection Apr 06 10:51:43 opera sudo[13760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 06 10:51:43 opera sudo[13760]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:43 opera sudo[13760]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:43 opera volumio-remote-updater[689]: [2024-04-06 10:51:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1712400700 101 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 10:51:43 opera volumio[13601]: info: Reloading queue from file Apr 06 10:51:43 opera sudo[13772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 06 10:51:43 opera sudo[13772]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:43 opera systemd[1]: Stopping RAAT DAEMON... Apr 06 10:51:43 opera systemd[1]: Starting RAAT DAEMON... Apr 06 10:51:43 opera systemd[1]: Started RAAT DAEMON. Apr 06 10:51:43 opera sudo[13772]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::setRepeat true single undefined Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::setRandom false Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:51:43 opera volumio[13601]: info: Raat Daemon started successfully Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioGetQueue Apr 06 10:51:43 opera volumio[13601]: info: CoreStateMachine::getQueue Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getQueue Apr 06 10:51:43 opera volumio[13601]: info: Listing playlists Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 10:51:43 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:43 opera volumio[13601]: info: Executing endpoint restartRAATSocket Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 06 10:51:43 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: Starting Shairport Sync Apr 06 10:51:43 opera volumio[13601]: info: Starting Shairport Sync Apr 06 10:51:43 opera sudo[13795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 10:51:43 opera sudo[13795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:43 opera sudo[13798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 10:51:43 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 06 10:51:43 opera sudo[13798]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:43 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 06 10:51:43 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 06 10:51:43 opera sudo[13795]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:43 opera systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 06 10:51:43 opera systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Apr 06 10:51:43 opera systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 06 10:51:43 opera sudo[13798]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:43 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:43 opera volumio[13601]: info: Shairport-Sync Started Apr 06 10:51:43 opera volumio[13601]: Error adding Membership: Error: addMembership EINVAL Apr 06 10:51:43 opera volumio[13601]: info: Shairport-Sync Started Apr 06 10:51:44 opera volumio[13601]: Discovery: adding 64ef0dd6-d70c-4d95-8e42-9d9011e2cc05 Apr 06 10:51:44 opera volumio[13601]: info: mDNS: Found device Opera-3 Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:44 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:44 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:44 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:44 opera volumio[13601]: Discovery: this is already registered, 64ef0dd6-d70c-4d95-8e42-9d9011e2cc05 Apr 06 10:51:44 opera volumio[13601]: info: mDNS: Found device Opera-3 Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:44 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:44 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:44 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 06 10:51:44 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 10:51:45 opera sudo[13840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 06 10:51:45 opera sudo[13840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:45 opera systemd[1]: Started Volumio Bluetooth Module. Apr 06 10:51:45 opera sudo[13840]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:46 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:46 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:46 opera volumio[13601]: info: Volumio BT Module successfully started Apr 06 10:51:46 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 10:51:46 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:46 opera volumio[13601]: info: Listing playlists Apr 06 10:51:46 opera volumio-streaming-daemon[13728]: Checking variant in static cache Apr 06 10:51:46 opera volumio[13601]: info: Fetching Streaming Services browse cache Apr 06 10:51:46 opera volumio[13601]: info: CoreCommandRouter::volumioGetState Apr 06 10:51:46 opera volumio[13601]: info: CorePlayQueue::getTrack 0 Apr 06 10:51:47 opera sudo[13852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 10:51:47 opera sudo[13852]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:47 opera sudo[13852]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:47 opera sudo[13859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 10:51:47 opera sudo[13859]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:47 opera sudo[13859]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:47 opera volumio[13601]: Discovery: adding 89cadb14-f203-4e5d-a3c9-03693fad9740 Apr 06 10:51:47 opera volumio[13601]: info: mDNS: Found device Opera-2 Apr 06 10:51:47 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:47 opera sudo[13871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 06 10:51:47 opera sudo[13871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 10:51:47 opera systemd[1]: Started UPnP Renderer front-end to MPD. Apr 06 10:51:47 opera sudo[13871]: pam_unix(sudo:session): session closed for user root Apr 06 10:51:47 opera volumio[13601]: info: Upmpdcli Daemon Started Apr 06 10:51:47 opera volumio[13601]: error: Could not retrieve plugin audio_interface multiroom Apr 06 10:51:57 opera volumio[13601]: info: Volumio called home Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::volumioPlay Apr 06 10:52:02 opera volumio[13601]: verbose: UNSET VOLATILE Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::play index 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::stop Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:52:02 opera volumio[13601]: verbose: UNSET VOLATILE Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::play index undefined Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:52:02 opera volumio[13601]: info: Apr 06 10:52:02 opera volumio[13601]: ---------------------------- MPD announces state update: player Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand stop took 55 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: ControllerMpd::getState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:52:02 opera volumio[13601]: info: Apr 06 10:52:02 opera volumio[13601]: ---------------------------- MPD announces system playlist update Apr 06 10:52:02 opera volumio[13601]: info: Ignoring MPD Status Update Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand clear took 3 milliseconds Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac" Apr 06 10:52:02 opera volumio[13601]: error: updateQueue error: null Apr 06 10:52:02 opera volumio[13601]: info: Apr 06 10:52:02 opera volumio[13601]: ---------------------------- MPD announces system playlist update Apr 06 10:52:02 opera volumio[13601]: info: Ignoring MPD Status Update Apr 06 10:52:02 opera volumio[13601]: info: ------------------------------ 8ms Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac" took 4 milliseconds Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:52:02 opera volumio[13601]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:52:02 opera volumio[13601]: info: ------------------------------ 18ms Apr 06 10:52:02 opera volumio[13601]: info: ------------------------------ 7ms Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand play took 4 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: Apr 06 10:52:02 opera volumio[13601]: ---------------------------- MPD announces state update: player Apr 06 10:52:02 opera volumio[13601]: info: ControllerMpd::getState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:52:02 opera volumio[13601]: info: Apr 06 10:52:02 opera volumio[13601]: ---------------------------- MPD announces state update: player Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: ControllerMpd::getState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseState Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:52:02 opera volumio[13601]: info: ControllerMpd::pushState Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::servicePushState Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"16 - 情人的眼淚","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac","trackType":"flac"} Apr 06 10:52:02 opera volumio[13601]: verbose: CURRENT POSITION 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::syncState stateService play Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:52:02 opera volumio[13601]: info: ------------------------------ 23ms Apr 06 10:52:02 opera volumio[13601]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 06 10:52:02 opera volumio[13601]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:52:02 opera volumio[13601]: info: ControllerMpd::pushState Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::servicePushState Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":290,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2429 Kbps","isStreaming":false,"title":"16 - 情人的眼淚","artist":"林憶蓮","album":"精選16首 SACD Best Collection ","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac","trackType":"flac"} Apr 06 10:52:02 opera volumio[13601]: verbose: CURRENT POSITION 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::syncState stateService play Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:52:02 opera volumio[13601]: info: Received an update from plugin. extracting info from payload Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:52:02 opera volumio[13601]: info: CoreStateMachine::pushState Apr 06 10:52:02 opera volumio[13601]: info: CorePlayQueue::getTrack 15 Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::volumioPushState Apr 06 10:52:02 opera volumio[13601]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:52:02 opera volumio[13601]: info: ------------------------------ 33ms Apr 06 10:52:02 opera volumio[13601]: info: Updating RAAT Signal Path Apr 06 10:52:02 opera volumio[13601]: info: Updating RAAT Signal Path Apr 06 10:52:02 opera volumio[13601]: info: Updating RAAT Signal Path Apr 06 10:52:06 opera volumio[13601]: info: Adding music-library/USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac to favourites using generic method Apr 06 10:52:06 opera volumio[13601]: info: Saving local item favourites Apr 06 10:52:06 opera volumio[13601]: info: Pushing Favourites {"uri":"/mnt/USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮 - 精選16首 SACD Best Collection 2004 24-88.2 SACD/16 - 情人的眼淚.flac","favourite":false} Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Joachim%20Eijlander/Bach%20Cello%20Suites%20Vol.1/9e3f3d43-e9c3-49c5-93ee-13bbf8a02b9b.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ben%20Webster/The%20Soul%20Of%20Ben%20Webster/3401c015-20f0-436a-a498-f246db317477.png' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Pacho%20Flores/Cantar/41fa11e7-bb8e-44ec-b830-1b061c276550.png' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nordic%20Brass%20Ensemble/EUROPEAN%20TOUR/4cbf3639-fd7d-4a55-a5b0-2b503e033a3f.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Berliner%20Philharmoniker%2C%20Sir%20Simon%20Rattle/Ludwig%20van%20Beethoven%3A%20Symphonies%20Nos.%201-9/71e4514d-8ad0-481b-98d5-718c9d9eb6e2.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Daniil%20Trifonov/Transcendental%3A%20Daniil%20Trifonov%20plays%20Franz%20Liszt/a0722166-f78d-412b-829f-d985256b005d.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Duke%20Ellington%20and%20His%20Orchestra/Ellington%20Indigos/04f08670-5932-4de0-a72a-169642300ab0.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Peter%20Gabriel/Shaking%20The%20Tree%20(Sixteen%20Golden%20Greats)/64d80181-4cb4-4d8c-b280-55121aed1b4f.jpg' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Pink%20Floyd/Echoes%3A%20The%20Best%20Of%20Pink%20Floyd/c3ce2faa-273a-4512-9d8d-fd358f8cd66c.png' Apr 06 10:52:07 opera volumio[13601]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/James%20Blake/James%20Blake/95d2ce44-26b4-4a58-8537-57a0f499a0e1.jpg' Apr 06 10:52:09 opera volumio[13601]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:52:09 opera volumio[13601]: { Error: connect ECONNREFUSED 157.240.12.36:443 Apr 06 10:52:09 opera volumio[13601]: at Object._errnoException (util.js:1022:11) Apr 06 10:52:09 opera volumio[13601]: at _exceptionWithHostPort (util.js:1044:20) Apr 06 10:52:09 opera volumio[13601]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 06 10:52:09 opera volumio[13601]: code: 'ECONNREFUSED', Apr 06 10:52:09 opera volumio[13601]: errno: 'ECONNREFUSED', Apr 06 10:52:09 opera volumio[13601]: syscall: 'connect', Apr 06 10:52:09 opera volumio[13601]: address: '157.240.12.36', Apr 06 10:52:09 opera volumio[13601]: port: 443 } Apr 06 10:52:09 opera volumio[13601]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:52:10 opera sudo[13955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 10:51 Apr 06 10:52:10 opera sudo[13955]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"