-- Logs begin at Sun 2024-03-31 05:06:50 UTC, end at Sat 2024-04-06 10:58:49 UTC. -- Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::ClearQueue Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:22 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::serviceStop Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::serviceStop Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::stop Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::clearPlayQueue Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::saveQueue Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::addQueueItems Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::addQueueItems Apr 06 10:57:22 opera volumio[13987]: info: Adding Item to queue: albums://%E6%9E%97%E6%86%B6%E8%93%AE/%E8%8F%AF%E7%B4%8D%E8%B6%85%E6%A5%B5%E5%93%81%E9%9F%B3%E8%89%B2%E7%B3%BB%E5%88%97%3A%20%E6%9E%97%E6%86%B6%E8%93%AE Apr 06 10:57:22 opera volumio[13987]: info: Exploding uri albums://%E6%9E%97%E6%86%B6%E8%93%AE/%E8%8F%AF%E7%B4%8D%E8%B6%85%E6%A5%B5%E5%93%81%E9%9F%B3%E8%89%B2%E7%B3%BB%E5%88%97%3A%20%E6%9E%97%E6%86%B6%E8%93%AE in service mpd Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Apr 06 10:57:22 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand stop took 23 milliseconds Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::saveQueue Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPlay Apr 06 10:57:22 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::play index 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:22 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::play index undefined Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/01 只要我活過哭過.aiff Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand status took 12 milliseconds Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand stop took 4 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:57:22 opera volumio[13987]: info: Apr 06 10:57:22 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:22 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand clear took 10 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/01 只要我活過哭過.aiff" Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"盼你在佌","artist":"林憶蓮","album":"林憶蓮's","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/林憶蓮's/01 盼你在佌.aiff","trackType":"aiff"} Apr 06 10:57:22 opera volumio[13987]: verbose: CURRENT POSITION 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState stateService stop Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: No code Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: ------------------------------ 40ms Apr 06 10:57:22 opera volumio[13987]: error: updateQueue error: null Apr 06 10:57:22 opera volumio[13987]: info: Apr 06 10:57:22 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:22 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:22 opera volumio[13987]: info: ------------------------------ 18ms Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/01 只要我活過哭過.aiff" took 17 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:57:22 opera volumio[13987]: info: ------------------------------ 11ms Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand play took 11 milliseconds Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Apr 06 10:57:22 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:22 opera volumio[13987]: info: Apr 06 10:57:22 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand status took 24 milliseconds Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":346,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只要我活過哭過","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/01 只要我活過哭過.aiff","trackType":"aiff"} Apr 06 10:57:22 opera volumio[13987]: verbose: CURRENT POSITION 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: ------------------------------ 34ms Apr 06 10:57:22 opera volumio[13987]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 06 10:57:22 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:22 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":580,"duration":346,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"只要我活過哭過","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/01 只要我活過哭過.aiff","trackType":"aiff"} Apr 06 10:57:22 opera volumio[13987]: verbose: CURRENT POSITION 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:57:22 opera volumio[13987]: info: Received an update from plugin. extracting info from payload Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:22 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:22 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:22 opera volumio[13987]: info: ------------------------------ 43ms Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:22 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::volumioPlay Apr 06 10:57:31 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::play index 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:31 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::serviceStop Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::serviceStop Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::stop Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:31 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand stop took 29 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::play index undefined Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/02 你是我的男人.aiff Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:31 opera volumio[13987]: info: Apr 06 10:57:31 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand stop took 4 milliseconds Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:57:31 opera volumio[13987]: info: Apr 06 10:57:31 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:31 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand clear took 3 milliseconds Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/02 你是我的男人.aiff" Apr 06 10:57:31 opera volumio[13987]: error: updateQueue error: null Apr 06 10:57:31 opera volumio[13987]: info: Apr 06 10:57:31 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:31 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:31 opera volumio[13987]: info: ------------------------------ 10ms Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/02 你是我的男人.aiff" took 9 milliseconds Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:57:31 opera volumio[13987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:57:31 opera volumio[13987]: info: ------------------------------ 18ms Apr 06 10:57:31 opera volumio[13987]: info: ------------------------------ 6ms Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand play took 2 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: Apr 06 10:57:31 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:31 opera volumio[13987]: info: Apr 06 10:57:31 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你是我的男人","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/02 你是我的男人.aiff","trackType":"aiff"} Apr 06 10:57:31 opera volumio[13987]: verbose: CURRENT POSITION 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:31 opera volumio[13987]: info: ------------------------------ 15ms Apr 06 10:57:31 opera volumio[13987]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 06 10:57:31 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:31 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":227,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"你是我的男人","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/02 你是我的男人.aiff","trackType":"aiff"} Apr 06 10:57:31 opera volumio[13987]: verbose: CURRENT POSITION 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:57:31 opera volumio[13987]: info: Received an update from plugin. extracting info from payload Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:31 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:31 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:31 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:31 opera volumio[13987]: info: ------------------------------ 23ms Apr 06 10:57:31 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:31 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:31 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:42 opera volumio[13987]: info: CoreCommandRouter::volumioPlay Apr 06 10:57:42 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:42 opera volumio[13987]: info: CoreStateMachine::play index 14 Apr 06 10:57:42 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:42 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:42 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:43 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::serviceStop Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::serviceStop Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::stop Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:43 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand stop took 77 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::play index undefined Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/15 此情只待成追憶.aiff Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:43 opera volumio[13987]: info: Apr 06 10:57:43 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand stop took 7 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:43 opera volumio[13987]: info: Apr 06 10:57:43 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:43 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand clear took 2 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/15 此情只待成追憶.aiff" Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:43 opera volumio[13987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:57:43 opera volumio[13987]: info: ------------------------------ 10ms Apr 06 10:57:43 opera volumio[13987]: error: updateQueue error: null Apr 06 10:57:43 opera volumio[13987]: info: Apr 06 10:57:43 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:43 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:43 opera volumio[13987]: info: ------------------------------ 5ms Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/15 此情只待成追憶.aiff" took 3 milliseconds Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:57:43 opera volumio[13987]: info: ------------------------------ 3ms Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand play took 2 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: Apr 06 10:57:43 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:43 opera volumio[13987]: info: Apr 06 10:57:43 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"此情只待成追憶","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/15 此情只待成追憶.aiff","trackType":"aiff"} Apr 06 10:57:43 opera volumio[13987]: verbose: CURRENT POSITION 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:43 opera volumio[13987]: info: ------------------------------ 13ms Apr 06 10:57:43 opera volumio[13987]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 06 10:57:43 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:43 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":240,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"此情只待成追憶","artist":"林憶蓮","album":"華納超極品音色系列: 林憶蓮","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RY8W11H/華納超極品音色系列_ 林憶蓮/15 此情只待成追憶.aiff","trackType":"aiff"} Apr 06 10:57:43 opera volumio[13987]: verbose: CURRENT POSITION 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:57:43 opera volumio[13987]: info: Received an update from plugin. extracting info from payload Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:43 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:43 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:43 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:43 opera volumio[13987]: info: ------------------------------ 22ms Apr 06 10:57:43 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:43 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:43 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::ClearQueue Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:48 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::serviceStop Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 14 Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::serviceStop Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::stop Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::clearPlayQueue Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::saveQueue Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::addQueueItems Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::addQueueItems Apr 06 10:57:48 opera volumio[13987]: info: Adding Item to queue: albums://Various%20Artists/Denon%20Hi%20Fi%20Strings Apr 06 10:57:48 opera volumio[13987]: info: Exploding uri albums://Various%20Artists/Denon%20Hi%20Fi%20Strings in service mpd Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::saveQueue Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushQueue Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPlay Apr 06 10:57:48 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::play index 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:48 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::play index undefined Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/01 Handel Water Music Suite #2 In D HWV 349 - Overture.flac Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:48 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:48 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:48 opera volumio[13987]: info: Apr 06 10:57:48 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand stop took 128 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand stop took 108 milliseconds Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:57:48 opera volumio[13987]: info: Apr 06 10:57:48 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:48 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand status took 1 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand clear took 1 milliseconds Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/01 Handel Water Music Suite #2 In D HWV 349 - Overture.flac" Apr 06 10:57:48 opera volumio[13987]: error: updateQueue error: null Apr 06 10:57:48 opera volumio[13987]: info: Apr 06 10:57:48 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:48 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:48 opera volumio[13987]: info: ------------------------------ 12ms Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/01 Handel Water Music Suite #2 In D HWV 349 - Overture.flac" took 12 milliseconds Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:57:48 opera volumio[13987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:57:48 opera volumio[13987]: info: ------------------------------ 24ms Apr 06 10:57:48 opera volumio[13987]: info: ------------------------------ 22ms Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand play took 11 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: Apr 06 10:57:48 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:48 opera volumio[13987]: info: Apr 06 10:57:48 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Handel: Water Music Suite #2 In D, HWV 349 - Overture","artist":"I Solisti Italiani","album":"Denon Hi Fi Strings","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/01 Handel Water Music Suite #2 In D HWV 349 - Overture.flac","trackType":"flac"} Apr 06 10:57:48 opera volumio[13987]: verbose: CURRENT POSITION 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:48 opera volumio[13987]: info: ------------------------------ 18ms Apr 06 10:57:48 opera volumio[13987]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 06 10:57:48 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:48 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":203,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Handel: Water Music Suite #2 In D, HWV 349 - Overture","artist":"I Solisti Italiani","album":"Denon Hi Fi Strings","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/01 Handel Water Music Suite #2 In D HWV 349 - Overture.flac","trackType":"flac"} Apr 06 10:57:48 opera volumio[13987]: verbose: CURRENT POSITION 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:57:48 opera volumio[13987]: info: Received an update from plugin. extracting info from payload Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:48 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:48 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:48 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:48 opera volumio[13987]: info: ------------------------------ 37ms Apr 06 10:57:48 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:48 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:48 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::volumioPlay Apr 06 10:57:59 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::play index 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::stop Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:59 opera volumio[13987]: verbose: UNSET VOLATILE Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::stPlaybackTimer Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::updateTrackBlock Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrackBlock Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::serviceStop Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 0 Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::serviceStop Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::stop Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:59 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand stop took 98 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::play index undefined Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::startPlaybackTimer Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::clearAddPlayTracks USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/02 Pizzicatio Polka.flac Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 10:57:59 opera volumio[13987]: info: Apr 06 10:57:59 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand stop took 3 milliseconds Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 10:57:59 opera volumio[13987]: info: Apr 06 10:57:59 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:59 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand status took 4 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand clear took 3 milliseconds Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/02 Pizzicatio Polka.flac" Apr 06 10:57:59 opera volumio[13987]: error: updateQueue error: null Apr 06 10:57:59 opera volumio[13987]: info: Apr 06 10:57:59 opera volumio[13987]: ---------------------------- MPD announces system playlist update Apr 06 10:57:59 opera volumio[13987]: info: Ignoring MPD Status Update Apr 06 10:57:59 opera volumio[13987]: info: ------------------------------ 4ms Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand add "USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/02 Pizzicatio Polka.flac" took 3 milliseconds Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand play Apr 06 10:57:59 opera volumio[13987]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 06 10:57:59 opera volumio[13987]: info: ------------------------------ 10ms Apr 06 10:57:59 opera volumio[13987]: info: ------------------------------ 3ms Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand play took 2 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: Apr 06 10:57:59 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:59 opera volumio[13987]: info: Apr 06 10:57:59 opera volumio[13987]: ---------------------------- MPD announces state update: player Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand status took 2 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::getState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand status Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand status took 3 milliseconds Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseState Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":131,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Pizzicatio Polka","artist":"I Solisti Italiani","album":"Denon Hi Fi Strings","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/02 Pizzicatio Polka.flac","trackType":"flac"} Apr 06 10:57:59 opera volumio[13987]: verbose: CURRENT POSITION 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus stop Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:59 opera volumio[13987]: info: ------------------------------ 29ms Apr 06 10:57:59 opera volumio[13987]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 06 10:57:59 opera volumio[13987]: verbose: ControllerMpd::parseTrackInfo Apr 06 10:57:59 opera volumio[13987]: info: ControllerMpd::pushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::servicePushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":131,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"Pizzicatio Polka","artist":"I Solisti Italiani","album":"Denon Hi Fi Strings","uri":"USB/PCM..600/$RECYCLE.BIN/S-1-5-21-1118571346-3405995946-3236739600-500/$RYL8PWO/Denon Hi Fi Strings/02 Pizzicatio Polka.flac","trackType":"flac"} Apr 06 10:57:59 opera volumio[13987]: verbose: CURRENT POSITION 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::syncState stateService play Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::syncState currentStatus play Apr 06 10:57:59 opera volumio[13987]: info: Received an update from plugin. extracting info from payload Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:59 opera volumio[13987]: info: CoreStateMachine::pushState Apr 06 10:57:59 opera volumio[13987]: info: CorePlayQueue::getTrack 1 Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::volumioPushState Apr 06 10:57:59 opera volumio[13987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 10:57:59 opera volumio[13987]: info: ------------------------------ 54ms Apr 06 10:57:59 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:59 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:57:59 opera volumio[13987]: info: Updating RAAT Signal Path Apr 06 10:58:40 opera volumio[13987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Andrea%20Bocelli/S%C3%AC%20(Spanish%20Deluxe)/a0e27db2-4fb0-4fa9-ab0e-4ce9be25b5f0.jpg' Apr 06 10:58:40 opera volumio[13987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Berliner%20Philharmoniker/Wagner%3A%20Der%20Ring%20des%20Nibelungen/4f1dc3f8-379f-4c65-b3cb-c0b15fb3a324.jpg' Apr 06 10:58:40 opera volumio[13987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hilary%20Hahn/Hilary%20Hahn%20-%20Original%20Album%20Classics/05809174-9755-46f7-81f4-9a1000cbd5ac.jpg' Apr 06 10:58:41 opera volumio[13987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/Lover/ac2cdeab-0b87-4143-8269-a4f508c329c8.jpg' Apr 06 10:58:41 opera volumio[13987]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lang%20Lang/Piano%20Book%20(Encore%20Edition)/8e32e4f6-34ae-435f-9290-957566db8e90.jpg' Apr 06 10:58:48 opera volumio[13987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:58:48 opera volumio[13987]: { Error: connect ETIMEDOUT 122.248.226.57:443 Apr 06 10:58:48 opera volumio[13987]: at Object._errnoException (util.js:1022:11) Apr 06 10:58:48 opera volumio[13987]: at _exceptionWithHostPort (util.js:1044:20) Apr 06 10:58:48 opera volumio[13987]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 06 10:58:48 opera volumio[13987]: code: 'ETIMEDOUT', Apr 06 10:58:48 opera volumio[13987]: errno: 'ETIMEDOUT', Apr 06 10:58:48 opera volumio[13987]: syscall: 'connect', Apr 06 10:58:48 opera volumio[13987]: address: '122.248.226.57', Apr 06 10:58:48 opera volumio[13987]: port: 443 } Apr 06 10:58:48 opera volumio[13987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 10:58:49 opera sudo[15174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 10:57 Apr 06 10:58:49 opera sudo[15174]: 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"