-- Logs begin at Sat 2025-08-30 09:27:45 UTC, end at Sat 2025-08-30 12:54:47 UTC. -- Aug 30 12:53:07 opera volumio[3367]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Aug 30 12:53:07 opera volumio[3367]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Aug 30 12:53:08 opera volumio[3367]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Aug 30 12:53:08 opera volumio[3367]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.113:5353): query type 65 is invalid Aug 30 12:53:08 opera volumio[5663]: Failed to stat '/mnt//USB/未命名' Aug 30 12:53:09 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:09 opera volumio[5663]: info: Aug 30 12:53:09 opera volumio[5663]: ---------------------------- MPD announces system playlist update Aug 30 12:53:09 opera volumio[5663]: info: Ignoring MPD Status Update Aug 30 12:53:09 opera volumio[5663]: info: Aug 30 12:53:09 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:10 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:10 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:10 opera volumio[5663]: info: Aug 30 12:53:10 opera volumio[5663]: ---------------------------- MPD announces state update: ss /USB/My Passport/Hires/DR-达人唱片/DR达人艺典MQS/REDMANSIONS红楼梦-DR/.Spotlight-V100/Store-V2/964781C1-EC8B-4C3D-BDB3-ED75DAB1BA93/journals.scan/retire.2: No such file or directory Aug 30 12:53:10 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:10 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:14 opera volumio[5663]: info: Aug 30 12:53:14 opera volumio[5663]: ---------------------------- MPD announces state update: ssport/Music/刘芳-情弥漫/05 原谅我吧心上人.wav: No such file or directory Aug 30 12:53:14 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:14 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 12:53:16 opera volumio[5663]: info: CURURI: albums:// Aug 30 12:53:16 opera volumio[5663]: listAlbums - loading Albums from cache Aug 30 12:53:16 opera volumio[5663]: error: updateQueue error: null Aug 30 12:53:16 opera volumio[5663]: info: ------------------------------ 6293ms Aug 30 12:53:16 opera volumio[5663]: info: sendMpdCommand status took 6239 milliseconds Aug 30 12:53:16 opera volumio[5663]: info: sendMpdCommand status took 6216 milliseconds Aug 30 12:53:16 opera volumio[5663]: info: sendMpdCommand status took 1693 milliseconds Aug 30 12:53:16 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:16 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:16 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:16 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 12:53:16 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState stateService stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: No code Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 12:53:16 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState stateService stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: No code Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 12:53:16 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState stateService stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: No code Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:16 opera volumio[5663]: info: ------------------------------ 6357ms Aug 30 12:53:16 opera volumio[5663]: info: ------------------------------ 6301ms Aug 30 12:53:16 opera volumio[5663]: info: ------------------------------ 1775ms Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::saveQueue Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPushQueue Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::updateTrackBlock Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrackBlock Aug 30 12:53:16 opera volumio[5663]: info: CoreCommandRouter::volumioPlay Aug 30 12:53:16 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::play index 0 Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::stop Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:16 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::play index undefined Aug 30 12:53:16 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:16 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:16 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::ClearQueue Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::stop Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:29 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::clearPlayQueue Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::saveQueue Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushQueue Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::addQueueItems Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::addQueueItems Aug 30 12:53:29 opera volumio[5663]: info: Adding Item to queue: albums://Bob%20James/Cool Aug 30 12:53:29 opera volumio[5663]: info: Exploding uri albums://Bob%20James/Cool in service mpd Aug 30 12:53:29 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::saveQueue Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushQueue Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::updateTrackBlock Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrackBlock Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPlay Aug 30 12:53:29 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::play index 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::stop Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:29 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::play index undefined Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::startPlaybackTimer Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::clearAddPlayTracks USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand stop took 3 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 12:53:29 opera volumio[5663]: info: Aug 30 12:53:29 opera volumio[5663]: ---------------------------- MPD announces system playlist update Aug 30 12:53:29 opera volumio[5663]: info: Ignoring MPD Status Update Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand clear took 4 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand add "USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac" Aug 30 12:53:29 opera volumio[5663]: error: updateQueue error: null Aug 30 12:53:29 opera volumio[5663]: info: Aug 30 12:53:29 opera volumio[5663]: ---------------------------- MPD announces system playlist update Aug 30 12:53:29 opera volumio[5663]: info: Ignoring MPD Status Update Aug 30 12:53:29 opera volumio[5663]: info: ------------------------------ 5ms Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand add "USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac" took 3 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand play Aug 30 12:53:29 opera volumio[5663]: info: ------------------------------ 9ms Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand play took 7 milliseconds Aug 30 12:53:29 opera volumio[5663]: info: Aug 30 12:53:29 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:29 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand status took 3 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 12:53:29 opera volumio[5663]: info: Aug 30 12:53:29 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:29 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::parseTrackInfo Aug 30 12:53:29 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Aug 30 12:53:29 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::syncState stateService play Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus stop Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:29 opera volumio[5663]: info: ------------------------------ 22ms Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand status took 14 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 12:53:29 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:29 opera volumio[5663]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 30 12:53:29 opera volumio[5663]: verbose: ControllerMpd::parseTrackInfo Aug 30 12:53:29 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Aug 30 12:53:29 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::syncState stateService play Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus play Aug 30 12:53:29 opera volumio[5663]: info: Received an update from plugin. extracting info from payload Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:29 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:29 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:29 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:29 opera volumio[5663]: info: ------------------------------ 49ms Aug 30 12:53:29 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:29 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::ClearQueue Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::stop Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:30 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::stPlaybackTimer Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::updateTrackBlock Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrackBlock Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::serviceStop Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::serviceStop Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::stop Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::clearPlayQueue Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::saveQueue Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushQueue Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::addQueueItems Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::addQueueItems Aug 30 12:53:30 opera volumio[5663]: info: Adding Item to queue: albums://Bob%20James/Cool Aug 30 12:53:30 opera volumio[5663]: info: Exploding uri albums://Bob%20James/Cool in service mpd Aug 30 12:53:30 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:30 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::saveQueue Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushQueue Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::updateTrackBlock Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrackBlock Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPlay Aug 30 12:53:30 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::play index 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::stop Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:30 opera volumio[5663]: verbose: UNSET VOLATILE Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::play index undefined Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::startPlaybackTimer Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::clearAddPlayTracks USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 12:53:30 opera volumio[5663]: info: Aug 30 12:53:30 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand stop took 45 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand stop took 12 milliseconds Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 12:53:30 opera volumio[5663]: info: Aug 30 12:53:30 opera volumio[5663]: ---------------------------- MPD announces system playlist update Aug 30 12:53:30 opera volumio[5663]: info: Ignoring MPD Status Update Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand status took 3 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand clear took 3 milliseconds Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand add "USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac" Aug 30 12:53:30 opera volumio[5663]: error: updateQueue error: null Aug 30 12:53:30 opera volumio[5663]: info: Aug 30 12:53:30 opera volumio[5663]: ---------------------------- MPD announces system playlist update Aug 30 12:53:30 opera volumio[5663]: info: Ignoring MPD Status Update Aug 30 12:53:30 opera volumio[5663]: info: ------------------------------ 6ms Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand add "USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac" took 2 milliseconds Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseTrackInfo Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand play Aug 30 12:53:30 opera volumio[5663]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 12:53:30 opera volumio[5663]: info: ------------------------------ 12ms Aug 30 12:53:30 opera volumio[5663]: info: ------------------------------ 7ms Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand play took 6 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: Aug 30 12:53:30 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:30 opera volumio[5663]: info: Aug 30 12:53:30 opera volumio[5663]: ---------------------------- MPD announces state update: player Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand status took 4 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::getState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand status Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand status took 2 milliseconds Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseState Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseTrackInfo Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Aug 30 12:53:30 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::syncState stateService play Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus stop Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:30 opera volumio[5663]: info: ------------------------------ 24ms Aug 30 12:53:30 opera volumio[5663]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 30 12:53:30 opera volumio[5663]: verbose: ControllerMpd::parseTrackInfo Aug 30 12:53:30 opera volumio[5663]: info: ControllerMpd::pushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::servicePushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":454,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Movin' On","artist":"Earl Klugh","album":"Cool","uri":"USB/My Passport/Music/Bob James/Cool/01 Bob James - Movin' On.flac","trackType":"flac"} Aug 30 12:53:30 opera volumio[5663]: verbose: CURRENT POSITION 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::syncState stateService play Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::syncState currentStatus play Aug 30 12:53:30 opera volumio[5663]: info: Received an update from plugin. extracting info from payload Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:30 opera volumio[5663]: info: CoreStateMachine::pushState Aug 30 12:53:30 opera volumio[5663]: info: CorePlayQueue::getTrack 0 Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::volumioPushState Aug 30 12:53:30 opera volumio[5663]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 12:53:30 opera volumio[5663]: info: ------------------------------ 42ms Aug 30 12:53:30 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:30 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:53:30 opera volumio[5663]: info: Updating RAAT Signal Path Aug 30 12:54:46 opera volumio[5663]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 12:54:46 opera volumio[5663]: { Error: connect ETIMEDOUT 103.228.130.27:443 Aug 30 12:54:46 opera volumio[5663]: at Object._errnoException (util.js:1022:11) Aug 30 12:54:46 opera volumio[5663]: at _exceptionWithHostPort (util.js:1044:20) Aug 30 12:54:46 opera volumio[5663]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 30 12:54:46 opera volumio[5663]: code: 'ETIMEDOUT', Aug 30 12:54:46 opera volumio[5663]: errno: 'ETIMEDOUT', Aug 30 12:54:46 opera volumio[5663]: syscall: 'connect', Aug 30 12:54:46 opera volumio[5663]: address: '103.228.130.27', Aug 30 12:54:46 opera volumio[5663]: port: 443 } Aug 30 12:54:46 opera volumio[5663]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 12:54:47 opera sudo[7354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 12:53 Aug 30 12:54:47 opera sudo[7354]: 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"