-- Logs begin at Thu 2026-01-08 20:07:48 CST, end at Thu 2026-01-08 23:01:54 CST. -- Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:00 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:00 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:00 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:00 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 2 milliseconds Jan 08 23:00:00 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 3 milliseconds Jan 08 23:00:00 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 14ms Jan 08 23:00:00 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" took 11 milliseconds Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 11ms Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 8ms Jan 08 23:00:00 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Jan 08 23:00:00 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 14ms Jan 08 23:00:00 volumio-x86 volumio[15748]: info: sendMpdCommand play took 10 milliseconds Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 8ms Jan 08 23:00:00 volumio-x86 volumio[15748]: info: ------------------------------ 6ms Jan 08 23:00:01 volumio-x86 volumio[15748]: info: MyVolumio login type: Token Jan 08 23:00:02 volumio-x86 volumio[15748]: info: Jan 08 23:00:02 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:02 volumio-x86 volumio[15748]: info: Jan 08 23:00:02 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:02 volumio-x86 volumio[15748]: info: Jan 08 23:00:02 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand status took 12 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand status took 11 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand status took 10 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 22 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 22 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 21 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:02 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ------------------------------ 133ms Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ------------------------------ 137ms Jan 08 23:00:02 volumio-x86 volumio[15748]: info: ------------------------------ 136ms Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:00:02 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 08 23:00:08 volumio-x86 volumio[15748]: info: CURURI: music-library/USB/F062-6255/Richie Kotzen/Electric Joy Jan 08 23:00:08 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::ClearQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::clearPlayQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::play index 0 Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:15 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:15 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:15 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:15 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 5 milliseconds Jan 08 23:00:15 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 2 milliseconds Jan 08 23:00:15 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" Jan 08 23:00:15 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:00:15 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" took 3 milliseconds Jan 08 23:00:15 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:00:15 volumio-x86 volumio[15748]: info: sendMpdCommand play took 5 milliseconds Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 3ms Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Jan 08 23:00:15 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:00:15 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::ClearQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::clearPlayQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::play index 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 6 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand status took 7 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 6 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand status took 5 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand status took 3 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: 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} Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: 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} Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 74ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 73ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 77ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 75 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" took 74 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 6ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:16 volumio-x86 volumio[15748]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 88ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Jan 08 23:00:16 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 17ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: sendMpdCommand play took 14 milliseconds Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 13ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: ------------------------------ 12ms Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::ClearQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::clearPlayQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::play index 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/03 Shufina.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/04 Acid Lips.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/05 Slow Blues.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/06 High Wire.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/07 Dr. Glee.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/08 Hot Rails.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/09 The Deece Song.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: Using cached record of: music-library/USB/F062-6255/Richie Kotzen/Electric Joy/01 B Funk.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:16 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac Jan 08 23:00:16 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 5 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand status took 6 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 6 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand status took 4 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: 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} Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 49ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand status took 53 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 52ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 49 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac" took 49 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 6ms Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: 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} Jan 08 23:00:17 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:17 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:17 volumio-x86 volumio[15748]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 88ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 92ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Jan 08 23:00:17 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 45ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: sendMpdCommand play took 42 milliseconds Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 9ms Jan 08 23:00:17 volumio-x86 volumio[15748]: info: ------------------------------ 8ms Jan 08 23:00:18 volumio-x86 volumio[15748]: info: Jan 08 23:00:18 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:18 volumio-x86 volumio[15748]: info: Jan 08 23:00:18 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:18 volumio-x86 volumio[15748]: info: Jan 08 23:00:18 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand status took 21 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand status took 20 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand status took 19 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 38 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 37 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 34 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: 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":"02 Electric Toy.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/Electric Joy/02 Electric Toy.flac","trackType":"flac"} Jan 08 23:00:18 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:18 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ------------------------------ 166ms Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ------------------------------ 163ms Jan 08 23:00:18 volumio-x86 volumio[15748]: info: ------------------------------ 161ms Jan 08 23:00:23 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:00:23 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:26 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 08 23:00:26 volumio-x86 volumio[15748]: info: CURURI: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/03 Dose.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/04 Hypnotist.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/05 Ultramatic.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/06 Trick.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/07 Stark.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/08 Hype.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/09 Tramp.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/10 Last Words.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F02%20Pulse%2C%20Pt.%20II.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/03 Dose.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F03%20Dose.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/03 Dose.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/04 Hypnotist.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F04%20Hypnotist.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/04 Hypnotist.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/05 Ultramatic.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F05%20Ultramatic.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/05 Ultramatic.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/06 Trick.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F06%20Trick.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/06 Trick.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/07 Stark.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F07%20Stark.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/07 Stark.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/08 Hype.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F08%20Hype.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/08 Hype.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/09 Tramp.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F09%20Tramp.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/09 Tramp.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/10 Last Words.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F10%20Last%20Words.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/10 Last Words.flac Jan 08 23:00:26 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac in service mpd Jan 08 23:00:26 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Richie%20Kotzen/The%20Inner%20Galactic%20Fusion%20Experience/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F01%20Pulse.flac&metadata=false Jan 08 23:00:26 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::ClearQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::clearPlayQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience in service mpd Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Richie%20Kotzen/The%20Inner%20Galactic%20Fusion%20Experience/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F01%20Pulse.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F02%20Pulse%2C%20Pt.%20II.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F03%20Dose.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/03 Dose.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F04%20Hypnotist.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/04 Hypnotist.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F05%20Ultramatic.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/05 Ultramatic.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F06%20Trick.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/06 Trick.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F07%20Stark.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/07 Stark.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F08%20Hype.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/08 Hype.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F09%20Tramp.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/09 Tramp.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=//extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FRichie%20Kotzen%2FThe%20Inner%20Galactic%20Fusion%20Experience%2F10%20Last%20Words.flac&metadata=false Jan 08 23:00:28 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/10 Last Words.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::play index 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 4 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 2 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac" Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 8ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac" took 6 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand play took 6 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 9ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 53 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 54 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 52 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Jan 08 23:00:28 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 6 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 3 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1025 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":455,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1025 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":455,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1025 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 80ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 95ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 94ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand status took 39 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"833 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 90ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 86ms Jan 08 23:00:28 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 78 milliseconds Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"Pulse","artist":"Richie Kotzen","album":"The Inner Galactic Fusion Experience","uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/01 Pulse.flac","trackType":"flac"} Jan 08 23:00:28 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:28 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:28 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:28 volumio-x86 volumio[15748]: info: ------------------------------ 138ms Jan 08 23:00:31 volumio-x86 volumio[15748]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 23:00:31 volumio-x86 volumio[15748]: info: Retrying Login Due to Network Error Jan 08 23:00:32 volumio-x86 volumio[15748]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Richie%20Kotzen/The%20Inner%20Galactic%20Fusion%20Experience/57a4d876-7bc6-4e5f-8421-2466f4c926a5.jpg' Jan 08 23:00:33 volumio-x86 volumio[15748]: info: VolumeController::SetAlsaVolume75 Jan 08 23:00:34 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:34 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:34 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:34 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand status took 3 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand status took 2 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:00:38 volumio-x86 volumio[15748]: 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} Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: 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} Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: 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} Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 65ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 64ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 63ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:38 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 12 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 8 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac" Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac" took 4 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 3ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 2ms Jan 08 23:00:38 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Jan 08 23:00:38 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 6ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: sendMpdCommand play took 4 milliseconds Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 3ms Jan 08 23:00:38 volumio-x86 volumio[15748]: info: ------------------------------ 2ms Jan 08 23:00:39 volumio-x86 volumio[15748]: info: Jan 08 23:00:39 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:39 volumio-x86 volumio[15748]: info: Jan 08 23:00:39 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:39 volumio-x86 volumio[15748]: info: Jan 08 23:00:39 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand status took 22 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand status took 20 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand status took 20 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 26 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 25 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 22 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:39 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:39 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ------------------------------ 152ms Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ------------------------------ 149ms Jan 08 23:00:39 volumio-x86 volumio[15748]: info: ------------------------------ 147ms Jan 08 23:00:41 volumio-x86 volumio[15748]: info: MyVolumio login type: Token Jan 08 23:00:44 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:00:44 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:00:47 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:47 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac Jan 08 23:00:47 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:00:47 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 2 milliseconds Jan 08 23:00:47 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 3 milliseconds Jan 08 23:00:47 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac" Jan 08 23:00:47 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:00:47 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac" took 4 milliseconds Jan 08 23:00:47 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 12ms Jan 08 23:00:47 volumio-x86 volumio[15748]: info: sendMpdCommand play took 8 milliseconds Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 8ms Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Jan 08 23:00:47 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 6ms Jan 08 23:00:47 volumio-x86 volumio[15748]: info: ------------------------------ 3ms Jan 08 23:00:48 volumio-x86 volumio[15748]: info: Jan 08 23:00:48 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:48 volumio-x86 volumio[15748]: info: Jan 08 23:00:48 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:48 volumio-x86 volumio[15748]: info: Jan 08 23:00:48 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand status took 21 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand status took 20 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand status took 20 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 29 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 28 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand clearerror took 25 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: 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":"02 Pulse, Pt. II.flac","artist":null,"album":null,"uri":"USB/F062-6255/Richie Kotzen/The Inner Galactic Fusion Experience/02 Pulse, Pt. II.flac","trackType":"flac"} Jan 08 23:00:48 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: No code Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:00:48 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ------------------------------ 169ms Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ------------------------------ 167ms Jan 08 23:00:48 volumio-x86 volumio[15748]: info: ------------------------------ 164ms Jan 08 23:01:04 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 08 23:01:04 volumio-x86 volumio[15748]: info: CURURI: music-library/USB/F062-6255/Blues Saraceno Jan 08 23:01:04 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:01:05 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:01:05 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 1 Jan 08 23:01:07 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:01:07 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 08 23:01:07 volumio-x86 volumio[15748]: info: CoreStateMachine::ClearQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::clearPlayQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::addQueueItems Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::addQueueItems Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Adding Item to queue: music-library/USB/F062-6255/Blues Saraceno/Hairpick Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick in service mpd Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F01%20Stinky%20Kitty.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F02%20Rabbit%20Soup.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/02 Rabbit Soup.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F03%20My%20Generation.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/03 My Generation.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F04%20King%20for%20a%20Day.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/04 King for a Day.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F05%20Pretty%20People.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/05 Pretty People.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F06%20Feedin'%20the%20Box.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/06 Feedin' the Box.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F07%20Fat%20Padding.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/07 Fat Padding.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F08%20Far%20From%20Home.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/08 Far From Home.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F09%20Chewing%20on%20Crayons.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/09 Chewing on Crayons.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F10%20Bouree.flac&metadata=false Jan 08 23:01:08 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/10 Bouree.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::saveQueue Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::updateTrackBlock Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrackBlock Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPlay Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::play index 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::stop Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::play index undefined Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::startPlaybackTimer Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::clearAddPlayTracks USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand stop Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand stop took 2 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand clear Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand clear took 1 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand add "USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac" Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: error: updateQueue error: null Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand add "USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac" took 3 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 2ms Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces system playlist update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Ignoring MPD Status Update Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 7ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand play took 4 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 4ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 5ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 18 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 19 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 17 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus stop Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 25ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Jan 08 23:01:08 volumio-x86 volumio[15748]: ---------------------------- MPD announces state update: player Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::getState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand status Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 6 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 7 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand status took 6 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseState Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"420 Kbps","isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":159,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1143 Kbps","isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 62ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 62ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 35 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: info: sendMpdCommand playlistinfo took 34 milliseconds Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: ControllerMpd::parseTrackInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1134 Kbps","isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"978 Kbps","isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ControllerMpd::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::servicePushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":269,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"978 Kbps","isStreaming":false,"title":"Stinky Kitty","artist":"Blues Saraceno","album":"Hairpick","uri":"USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac","trackType":"flac"} Jan 08 23:01:08 volumio-x86 volumio[15748]: verbose: CURRENT POSITION 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState stateService play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::syncState currentStatus play Jan 08 23:01:08 volumio-x86 volumio[15748]: info: Received an update from plugin. extracting info from payload Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreStateMachine::pushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:08 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioPushState Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 94ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 90ms Jan 08 23:01:08 volumio-x86 volumio[15748]: info: ------------------------------ 90ms Jan 08 23:01:11 volumio-x86 volumio[15748]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 23:01:11 volumio-x86 volumio[15748]: info: Retrying Login Due to Network Error Jan 08 23:01:13 volumio-x86 volumio[15748]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 08 23:01:13 volumio-x86 volumio[15748]: info: CURURI: music-library/USB/F062-6255/Blues Saraceno/Hairpick Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preload queue cleared Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/02 Rabbit Soup.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/03 My Generation.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/04 King for a Day.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/05 Pretty People.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/06 Feedin' the Box.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/07 Fat Padding.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/08 Far From Home.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/09 Chewing on Crayons.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Preloading song: music-library/USB/F062-6255/Blues Saraceno/Hairpick/10 Bouree.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F01%20Stinky%20Kitty.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/01 Stinky Kitty.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/02 Rabbit Soup.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F02%20Rabbit%20Soup.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/02 Rabbit Soup.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/03 My Generation.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F03%20My%20Generation.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/03 My Generation.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/04 King for a Day.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F04%20King%20for%20a%20Day.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/04 King for a Day.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/05 Pretty People.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F05%20Pretty%20People.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/05 Pretty People.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/06 Feedin' the Box.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F06%20Feedin'%20the%20Box.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/06 Feedin' the Box.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/07 Fat Padding.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F07%20Fat%20Padding.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/07 Fat Padding.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/08 Far From Home.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F08%20Far%20From%20Home.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/08 Far From Home.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/09 Chewing on Crayons.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F09%20Chewing%20on%20Crayons.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/09 Chewing on Crayons.flac Jan 08 23:01:13 volumio-x86 volumio[15748]: info: Exploding uri music-library/USB/F062-6255/Blues Saraceno/Hairpick/10 Bouree.flac in service mpd Jan 08 23:01:13 volumio-x86 volumio[15748]: info: ALBUMART /albumart?cacheid=373&web=Blues%20Saraceno/Hairpick/extralarge&path=%2Fmnt%2FUSB%2FF062-6255%2FBlues%20Saraceno%2FHairpick%2F10%20Bouree.flac&metadata=false Jan 08 23:01:13 volumio-x86 volumio[15748]: info: URI /mnt/USB/F062-6255/Blues Saraceno/Hairpick/10 Bouree.flac Jan 08 23:01:21 volumio-x86 volumio[15748]: info: MyVolumio login type: Token Jan 08 23:01:26 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:01:26 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:26 volumio-x86 sudo[16914]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 08 23:01:26 volumio-x86 sudo[16914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Fatal Python error: (pygame parachute) Segmentation Fault Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Current thread 0x00007f2c3e92a700 (most recent call first): Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145 in init_display Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "./screensaver/volumio_peppymeter.py", line 113 in meter_thread Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Thread 0x00007f2c3f96c700 (most recent call first): Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 81 in _path_stat Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 953 in path_stats Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 812 in get_code Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 724 in exec_module Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 677 in _load_unlocked Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 967 in _find_and_load_unlocked Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "", line 983 in _find_and_load Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/utils.py", line 172 in get_netrc_auth Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 449 in prepare_request Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 519 in request Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 536 in _send_request Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 297 in _connect_polling Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 195 in connect Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 277 in connect Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 123 in run Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Thread 0x00007f2c3f16b700 (most recent call first): Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 175 in get_data Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Thread 0x00007f2c46265740 (most recent call first): Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 296 in wait Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 552 in wait Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 852 in start Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: File "./screensaver/volumio_peppymeter.py", line 172 in Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: pygame 1.9.4.post1 Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 23:01:28 volumio-x86 sudo[16914]: pam_unix(sudo:session): session closed for user root Jan 08 23:01:28 volumio-x86 screensaver.sh[808]: /home/volumio/peppy_screensaver/screensaver.sh: line 28: 16914 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Jan 08 23:01:49 volumio-x86 volumio[15748]: info: CoreCommandRouter::volumioGetState Jan 08 23:01:49 volumio-x86 volumio[15748]: info: CorePlayQueue::getTrack 0 Jan 08 23:01:49 volumio-x86 sudo[16971]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jan 08 23:01:49 volumio-x86 sudo[16971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 08 23:01:51 volumio-x86 volumio[15748]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jan 08 23:01:51 volumio-x86 volumio[15748]: info: Retrying Login Due to Network Error Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Fatal Python error: (pygame parachute) Segmentation Fault Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Current thread 0x00007f09576b9700 (most recent call first): Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145 in init_display Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "./screensaver/volumio_peppymeter.py", line 113 in meter_thread Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Thread 0x00007f0957eba700 (most recent call first): Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 917 in get_data Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 818 in get_code Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 724 in exec_module Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 677 in _load_unlocked Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 967 in _find_and_load_unlocked Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "", line 983 in _find_and_load Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/utils.py", line 172 in get_netrc_auth Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 449 in prepare_request Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 519 in request Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 536 in _send_request Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 297 in _connect_polling Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 195 in connect Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 277 in connect Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 123 in run Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Thread 0x00007f09586fb700 (most recent call first): Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 175 in get_data Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 865 in run Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Thread 0x00007f095eff4740 (most recent call first): Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 296 in wait Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 552 in wait Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "/usr/lib/python3.7/threading.py", line 852 in start Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: File "./screensaver/volumio_peppymeter.py", line 172 in Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: pygame 1.9.4.post1 Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: Hello from the pygame community. https://www.pygame.org/contribute.html Jan 08 23:01:52 volumio-x86 sudo[16971]: pam_unix(sudo:session): session closed for user root Jan 08 23:01:52 volumio-x86 screensaver.sh[808]: /home/volumio/peppy_screensaver/screensaver.sh: line 28: 16971 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Jan 08 23:01:53 volumio-x86 volumio[15748]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 23:01:53 volumio-x86 volumio[15748]: Error: connect ETIMEDOUT 104.244.46.208:443 Jan 08 23:01:53 volumio-x86 volumio[15748]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 08 23:01:53 volumio-x86 volumio[15748]: errno: -110, Jan 08 23:01:53 volumio-x86 volumio[15748]: code: 'ETIMEDOUT', Jan 08 23:01:53 volumio-x86 volumio[15748]: syscall: 'connect', Jan 08 23:01:53 volumio-x86 volumio[15748]: address: '104.244.46.208', Jan 08 23:01:53 volumio-x86 volumio[15748]: port: 443 Jan 08 23:01:53 volumio-x86 volumio[15748]: } Jan 08 23:01:53 volumio-x86 volumio[15748]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 23:01:54 volumio-x86 sudo[17002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-08 23:00 Jan 08 23:01:54 volumio-x86 sudo[17002]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"