-- Logs begin at Tue 2024-10-22 20:38:08 CST, end at Tue 2024-10-22 20:47:43 CST. -- Oct 22 20:46:07 myvolumio volumio[856]: info: Preload queue cleared Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::ClearQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::stop Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::stPlaybackTimer Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::updateTrackBlock Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrackBlock Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 3 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::serviceStop Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 3 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::serviceStop Oct 22 20:46:07 myvolumio volumio[856]: info: ControllerMpd::stop Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::clearPlayQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::saveQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::addQueueItems Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::addQueueItems Oct 22 20:46:07 myvolumio volumio[856]: info: Preload queue cleared Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Happy To Hang Around.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Happy To Hang Around.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/How Many Hearts.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/How Many Hearts.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Love Will Come Through.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Love Will Come Through.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mid-Life Krysis.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mid-Life Krysis.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Paperclips.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Paperclips.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Peace The Fuck Out.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Peace The Fuck Out.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Quicksand.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Quicksand.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Somewhere Else.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Somewhere Else.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Beautiful Occupation.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Beautiful Occupation.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Walking Down The Hill.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Walking Down The Hill.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mother (Radio Edit).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mother (Radio Edit).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mother.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mother.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Definition Of Wrong.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Definition Of Wrong.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Don’t Be Shy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Don’t Be Shy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Enemy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Enemy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Sea.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Sea.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/as You are.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/as You are.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Driftwood.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Driftwood.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Luv.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Luv.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/She’s So Strange.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/She’s So Strange.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Fear.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Fear.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Last Laugh of the Laughter.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Last Laugh of the Laughter.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Writing to Reach You.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Writing to Reach You.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/C’est La Narcoleptic.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/C’est La Narcoleptic.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/L dot Y.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/L dot Y.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/A Different Room.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/A Different Room.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Anniversary (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Anniversary (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Another Guy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Another Guy.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Boxes.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Boxes.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Ferris Wheel (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Ferris Wheel (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/New Shoes.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/New Shoes.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/On My Wall.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/On My Wall.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Parallel Lines (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Parallel Lines (Bonus Track).mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Reminder.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Reminder.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Big Screen.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Big Screen.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Warning Sign.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Warning Sign.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Where You Stand.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Where You Stand.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Why does it always rain on me.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Why does it always rain on me.mp3 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::saveQueue Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::updateTrackBlock Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrackBlock Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPlay Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::play index 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::stop Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::play index undefined Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::startPlaybackTimer Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::clearAddPlayTracks USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 20:46:07 myvolumio volumio[856]: info: Oct 22 20:46:07 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand stop took 61 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand stop took 9 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 20:46:07 myvolumio volumio[856]: info: Oct 22 20:46:07 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:07 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:07 myvolumio volumio[856]: info: Oct 22 20:46:07 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:07 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand status took 10 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand clear took 26 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand status took 23 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand add "USB/592C-B353/Travis/Moving.mp3" Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:07 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: 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} Oct 22 20:46:07 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService stop Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:07 myvolumio volumio[856]: info: No code Oct 22 20:46:07 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:07 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:07 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:07 myvolumio volumio[856]: info: ------------------------------ 82ms Oct 22 20:46:07 myvolumio volumio[856]: error: updateQueue error: null Oct 22 20:46:07 myvolumio volumio[856]: info: Oct 22 20:46:07 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:07 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:07 myvolumio volumio[856]: info: ------------------------------ 86ms Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: info: sendMpdCommand add "USB/592C-B353/Travis/Moving.mp3" took 67 milliseconds Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:07 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand play Oct 22 20:46:07 myvolumio volumio[856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 20:46:07 myvolumio volumio[856]: info: ------------------------------ 102ms Oct 22 20:46:07 myvolumio volumio[856]: info: Oct 22 20:46:07 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:07 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 33ms Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand play took 31 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 26ms Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:08 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 27 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 26 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 26ms Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 25 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: info: Preload queue cleared Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::ClearQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::stop Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::clearPlayQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::saveQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::addQueueItems Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::addQueueItems Oct 22 20:46:08 myvolumio volumio[856]: info: Preload queue cleared Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Happy To Hang Around.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Happy To Hang Around.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/How Many Hearts.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/How Many Hearts.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Love Will Come Through.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Love Will Come Through.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mid-Life Krysis.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mid-Life Krysis.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Paperclips.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Paperclips.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Peace The Fuck Out.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Peace The Fuck Out.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Quicksand.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Quicksand.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Somewhere Else.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Somewhere Else.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Beautiful Occupation.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Beautiful Occupation.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Walking Down The Hill.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Walking Down The Hill.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mother (Radio Edit).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mother (Radio Edit).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Mother.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Mother.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Definition Of Wrong.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Definition Of Wrong.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Don’t Be Shy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Don’t Be Shy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Enemy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Enemy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Sea.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Sea.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/as You are.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/as You are.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Driftwood.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Driftwood.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Luv.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Luv.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/She’s So Strange.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/She’s So Strange.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Fear.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Fear.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Last Laugh of the Laughter.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Last Laugh of the Laughter.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Writing to Reach You.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Writing to Reach You.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/C’est La Narcoleptic.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/C’est La Narcoleptic.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/L dot Y.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/L dot Y.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/A Different Room.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/A Different Room.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Anniversary (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Anniversary (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Another Guy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Another Guy.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Boxes.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Boxes.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Ferris Wheel (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Ferris Wheel (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/New Shoes.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/New Shoes.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/On My Wall.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/On My Wall.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Parallel Lines (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Parallel Lines (Bonus Track).mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Reminder.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Reminder.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/The Big Screen.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/The Big Screen.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Warning Sign.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Warning Sign.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Where You Stand.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Where You Stand.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Adding Item to queue: music-library/USB/592C-B353/Travis/Why does it always rain on me.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: Using cached record of: music-library/USB/592C-B353/Travis/Why does it always rain on me.mp3 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::saveQueue Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::updateTrackBlock Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrackBlock Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPlay Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::play index 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::stop Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::play index undefined Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::startPlaybackTimer Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::clearAddPlayTracks USB/592C-B353/Travis/Moving.mp3 Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 107 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 96 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 96 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand stop took 22 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 5 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus stop Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 163ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 193ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 187ms Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:08 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:08 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: error: updateQueue error: null Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 83 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 83 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand clear took 82 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 82 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 10ms Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand add "USB/592C-B353/Travis/Moving.mp3" Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 231ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 127ms Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:08 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: error: updateQueue error: null Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces system playlist update Oct 22 20:46:08 myvolumio volumio[856]: info: Ignoring MPD Status Update Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 49ms Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 41 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand add "USB/592C-B353/Travis/Moving.mp3" took 40 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 5ms Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand play Oct 22 20:46:08 myvolumio volumio[856]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 134ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 14ms Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand play took 15 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 8 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 3 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: info: Oct 22 20:46:08 myvolumio volumio[856]: ---------------------------- MPD announces state update: player Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::getState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand status Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 7 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand status took 3 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseState Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 111ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 106ms Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 99 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: info: sendMpdCommand playlistinfo took 100 milliseconds Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: verbose: ControllerMpd::parseTrackInfo Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ControllerMpd::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::servicePushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":270,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Moving","artist":"Travis","album":"Where You Stand","uri":"USB/592C-B353/Travis/Moving.mp3","trackType":"mp3"} Oct 22 20:46:08 myvolumio volumio[856]: verbose: CURRENT POSITION 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState stateService play Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::syncState currentStatus play Oct 22 20:46:08 myvolumio volumio[856]: info: Received an update from plugin. extracting info from payload Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: CoreStateMachine::pushState Oct 22 20:46:08 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:08 myvolumio volumio[856]: info: CoreCommandRouter::volumioPushState Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 240ms Oct 22 20:46:08 myvolumio volumio[856]: info: ------------------------------ 236ms Oct 22 20:46:41 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:46:41 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:45 myvolumio volumio[856]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Travis/12%20Memories/0cfe11ab-e078-4f75-9723-a7b6e4d02caa.png' Oct 22 20:46:49 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:46:49 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:46:56 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:46:56 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:00 myvolumio volumio[856]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 20:47:00 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 20:47:01 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 22 20:47:01 myvolumio sudo[2987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 22 20:47:01 myvolumio sudo[2987]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[2987]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio sudo[2993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:01 myvolumio sudo[2993]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[2993]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio sudo[3002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:01 myvolumio sudo[3002]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[3002]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:01 myvolumio sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[3006]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio sudo[3012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 20:47:01 myvolumio sudo[3012]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[3012]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 22 20:47:01 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 22 20:47:01 myvolumio sudo[3016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 20:47:01 myvolumio sudo[3016]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:01 myvolumio sudo[3016]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:01 myvolumio sudo[3019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 22 20:47:01 myvolumio sudo[3019]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:03 myvolumio sudo[3019]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:11 myvolumio ntpd[1225]: 193.182.111.142 local addr 192.168.3.135 -> Oct 22 20:47:17 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 22 20:47:17 myvolumio volumio[856]: info: Saving new wireless network Oct 22 20:47:17 myvolumio sudo[3024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Oct 22 20:47:17 myvolumio sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:17 myvolumio sudo[3024]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:17 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 22 20:47:17 myvolumio volumio[856]: info: Discovery: Restarting Advertising due to device name change Oct 22 20:47:18 myvolumio sudo[3027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 22 20:47:18 myvolumio sudo[3027]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:18 myvolumio systemd[1]: Starting Wireless Services... Oct 22 20:47:18 myvolumio wireless.js[3029]: Cleaning previous... Oct 22 20:47:18 myvolumio sudo[3043]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 22 20:47:18 myvolumio sudo[3043]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:18 myvolumio sudo[3043]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:18 myvolumio sudo[3045]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 22 20:47:18 myvolumio sudo[3045]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:18 myvolumio sudo[3045]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:18 myvolumio volumio[856]: info: Discovery: A device disappeared from network Oct 22 20:47:19 myvolumio wireless.js[3029]: Stopped aP Oct 22 20:47:19 myvolumio sudo[3052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 22 20:47:19 myvolumio sudo[3052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:19 myvolumio sudo[3052]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:19 myvolumio sudo[3054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 22 20:47:19 myvolumio sudo[3054]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:19 myvolumio sudo[3054]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:19 myvolumio sudo[3062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 22 20:47:19 myvolumio sudo[3062]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:19 myvolumio sudo[3062]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:19 myvolumio sudo[3064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 22 20:47:19 myvolumio sudo[3064]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio sudo[3064]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:20 myvolumio wireless.js[3029]: SETTING APPROPRIATE REG DOMAIN: CN Oct 22 20:47:20 myvolumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 22 20:47:20 myvolumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio sudo[3069]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:20 myvolumio sudo[3071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Oct 22 20:47:20 myvolumio sudo[3071]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio sudo[3071]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:20 myvolumio wireless.js[3029]: SUCCESSFULLY SET NEW REGDOMAIN: CN Oct 22 20:47:20 myvolumio wireless.js[3029]: Start wireless flow Oct 22 20:47:20 myvolumio wireless.js[3029]: Stopped hotspot (if there).. Oct 22 20:47:20 myvolumio sudo[3076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 22 20:47:20 myvolumio sudo[3076]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio sudo[3076]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:20 myvolumio sudo[3078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 22 20:47:20 myvolumio sudo[3078]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio sudo[3078]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:20 myvolumio wireless.js[3029]: DHCP IP Oct 22 20:47:20 myvolumio wireless.js[3029]: Start ap Oct 22 20:47:20 myvolumio wpa_supplicant[3080]: Successfully initialized wpa_supplicant Oct 22 20:47:20 myvolumio sudo[3081]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 22 20:47:20 myvolumio sudo[3081]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:20 myvolumio dhcpcd[3082]: sending commands to master dhcpcd process Oct 22 20:47:20 myvolumio dhcpcd[737]: control command: /sbin/dhcpcd Oct 22 20:47:20 myvolumio sudo[3081]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:21 myvolumio wireless.js[3029]: trying... Oct 22 20:47:21 myvolumio sudo[3085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:21 myvolumio sudo[3085]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:21 myvolumio sudo[3085]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:21 myvolumio wpa_supplicant[3083]: wlan0: SME: Trying to authenticate with c0:b4:7d:56:21:d0 (SSID='Joyce's HONOR' freq=2412 MHz) Oct 22 20:47:21 myvolumio kernel: wlan0: authenticate with c0:b4:7d:56:21:d0 Oct 22 20:47:21 myvolumio kernel: wlan0: 80 MHz not supported, disabling VHT Oct 22 20:47:21 myvolumio kernel: wlan0: send auth to c0:b4:7d:56:21:d0 (try 1/3) Oct 22 20:47:21 myvolumio wpa_supplicant[3083]: wlan0: Trying to associate with c0:b4:7d:56:21:d0 (SSID='Joyce's HONOR' freq=2412 MHz) Oct 22 20:47:21 myvolumio kernel: wlan0: authenticated Oct 22 20:47:21 myvolumio kernel: wlan0: associate with c0:b4:7d:56:21:d0 (try 1/3) Oct 22 20:47:21 myvolumio kernel: wlan0: RX AssocResp from c0:b4:7d:56:21:d0 (capab=0x1431 status=0 aid=208) Oct 22 20:47:21 myvolumio kernel: usb 3-1: rtl8xxxu_bss_info_changed: HT supported Oct 22 20:47:21 myvolumio wpa_supplicant[3083]: wlan0: Associated with c0:b4:7d:56:21:d0 Oct 22 20:47:21 myvolumio wpa_supplicant[3083]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 22 20:47:21 myvolumio wpa_supplicant[3083]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Oct 22 20:47:21 myvolumio kernel: wlan0: associated Oct 22 20:47:22 myvolumio wpa_supplicant[3083]: wlan0: WPA: Key negotiation completed with c0:b4:7d:56:21:d0 [PTK=CCMP GTK=CCMP] Oct 22 20:47:22 myvolumio wpa_supplicant[3083]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:b4:7d:56:21:d0 completed [id=0 id_str=] Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: carrier acquired Oct 22 20:47:22 myvolumio kernel: wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by c0:b4:7d:56:21:d0 Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: IAID 40:76:62:c0 Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: soliciting a DHCP lease Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: offered 192.168.3.166 from 192.168.3.1 Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: probing address 192.168.3.166/24 Oct 22 20:47:22 myvolumio dhcpcd[737]: wlan0: soliciting an IPv6 router Oct 22 20:47:22 myvolumio wireless.js[3029]: trying... Oct 22 20:47:22 myvolumio sudo[3105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:22 myvolumio sudo[3105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:22 myvolumio sudo[3105]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:22 myvolumio volumio[856]: info: Discovery: Started advertising with name: MyVolumio Oct 22 20:47:23 myvolumio wireless.js[3029]: trying... Oct 22 20:47:23 myvolumio sudo[3109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:23 myvolumio sudo[3109]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:23 myvolumio sudo[3109]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:24 myvolumio volumio[856]: info: Discovery: adding 21238f71-3ea2-49d2-ab48-492971a01021 Oct 22 20:47:24 myvolumio volumio[856]: info: Discovery: Found device MyVolumio Oct 22 20:47:24 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:24 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:24 myvolumio wireless.js[3029]: trying... Oct 22 20:47:24 myvolumio sudo[3113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:24 myvolumio sudo[3113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:24 myvolumio sudo[3113]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:25 myvolumio wireless.js[3029]: trying... Oct 22 20:47:25 myvolumio sudo[3116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:25 myvolumio sudo[3116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:25 myvolumio sudo[3116]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:26 myvolumio dhcpcd[737]: wlan0: leased 192.168.3.166 for 86400 seconds Oct 22 20:47:26 myvolumio dhcpcd[737]: wlan0: adding route to 192.168.3.0/24 Oct 22 20:47:26 myvolumio dhcpcd[737]: wlan0: adding default route via 192.168.3.1 Oct 22 20:47:26 myvolumio dhcpcd[737]: wlan0: dhcp_envoption 213: Operation not supported Oct 22 20:47:26 myvolumio dhcpcd[737]: wlan0: dhcp_envoption 213: Operation not supported Oct 22 20:47:26 myvolumio avahi-daemon[640]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.166. Oct 22 20:47:26 myvolumio avahi-daemon[640]: New relevant interface wlan0.IPv4 for mDNS. Oct 22 20:47:26 myvolumio avahi-daemon[640]: Registering new address record for 192.168.3.166 on wlan0.IPv4. Oct 22 20:47:26 myvolumio wireless.js[3029]: trying... Oct 22 20:47:26 myvolumio sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 22 20:47:26 myvolumio sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:26 myvolumio sudo[3144]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:26 myvolumio wireless.js[3029]: Connected to: ----Joyce's HONOR Oct 22 20:47:26 myvolumio wireless.js[3029]: ---- Oct 22 20:47:26 myvolumio sudo[3151]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 20:47:26 myvolumio sudo[3151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:26 myvolumio sudo[3151]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:26 myvolumio wireless.js[3029]: ... joined AP, wlan0 IPv4 is 192.168.3.166, ipV6 is undefined Oct 22 20:47:26 myvolumio wireless.js[3029]: It's done! AP Oct 22 20:47:27 myvolumio ntpd[1225]: ntpd exiting on signal 15 (Terminated) Oct 22 20:47:27 myvolumio ntpd[1225]: 108.59.2.24 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio iwconfig[3157]: Error for wireless request "Set Power Management" (8B2C) : Oct 22 20:47:27 myvolumio iwconfig[3157]: SET failed on device wlan0 ; Operation not supported. Oct 22 20:47:27 myvolumio ntpd[1225]: 202.118.1.130 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 116.203.151.74 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 78.46.102.180 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 202.112.29.82 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 84.16.73.33 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 119.28.183.184 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio ntpd[1225]: 162.159.200.123 local addr 192.168.3.135 -> Oct 22 20:47:27 myvolumio systemd[1]: Stopping Network Time Service... Oct 22 20:47:27 myvolumio systemd[1]: Started Wireless Services. Oct 22 20:47:27 myvolumio sudo[3027]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:27 myvolumio systemd[1]: ntp.service: Succeeded. Oct 22 20:47:27 myvolumio systemd[1]: Stopped Network Time Service. Oct 22 20:47:27 myvolumio systemd[1]: Starting Network Time Service... Oct 22 20:47:27 myvolumio ntpd[3160]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 22 20:47:27 myvolumio ntpd[3160]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 22 20:47:27 myvolumio systemd[1]: Started Network Time Service. Oct 22 20:47:27 myvolumio ntpd[3166]: proto: precision = 0.327 usec (-21) Oct 22 20:47:27 myvolumio ntpd[3166]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 22 20:47:27 myvolumio ntpd[3166]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 22 20:47:27 myvolumio ntpd[3166]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 665 days ago Oct 22 20:47:27 myvolumio ntpd[3166]: Listen and drop on 0 v6wildcard [::]:123 Oct 22 20:47:27 myvolumio ntpd[3166]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 22 20:47:27 myvolumio ntpd[3166]: Listen normally on 2 lo 127.0.0.1:123 Oct 22 20:47:27 myvolumio ntpd[3166]: Listen normally on 3 eth0 192.168.3.135:123 Oct 22 20:47:27 myvolumio ntpd[3166]: Listen normally on 4 wlan0 192.168.3.166:123 Oct 22 20:47:27 myvolumio ntpd[3166]: Listening on routing socket on fd #21 for interface updates Oct 22 20:47:27 myvolumio ntpd[3166]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 22 20:47:27 myvolumio ntpd[3166]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 22 20:47:27 myvolumio volumio[856]: info: Discovery: this is already registered, 21238f71-3ea2-49d2-ab48-492971a01021 Oct 22 20:47:27 myvolumio volumio[856]: info: Discovery: Found device MyVolumio Oct 22 20:47:27 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:27 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:28 myvolumio ntpd[3166]: Soliciting pool server 78.46.102.180 Oct 22 20:47:29 myvolumio ntpd[3166]: Soliciting pool server 193.182.111.12 Oct 22 20:47:30 myvolumio ntpd[3166]: Soliciting pool server 84.16.67.12 Oct 22 20:47:30 myvolumio ntpd[3166]: Soliciting pool server 202.112.29.82 Oct 22 20:47:30 myvolumio kernel: r8169 0000:02:00.0 eth0: Link is Down Oct 22 20:47:30 myvolumio dhcpcd[737]: eth0: carrier lost Oct 22 20:47:30 myvolumio avahi-daemon[640]: Withdrawing address record for 192.168.3.135 on eth0. Oct 22 20:47:30 myvolumio avahi-daemon[640]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.3.135. Oct 22 20:47:30 myvolumio avahi-daemon[640]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 22 20:47:30 myvolumio volumio[856]: info: Discovery: A device disappeared from network Oct 22 20:47:30 myvolumio dhcpcd[737]: eth0: deleting route to 192.168.3.0/24 Oct 22 20:47:30 myvolumio dhcpcd[737]: eth0: deleting default route via 192.168.3.1 Oct 22 20:47:30 myvolumio dhcpcd[737]: eth0: dhcp_envoption 213: Operation not supported Oct 22 20:47:30 myvolumio dhcpcd[737]: eth0: dhcp_envoption 213: Operation not supported Oct 22 20:47:30 myvolumio ifplugd(eth0)[839]: Link beat lost. Oct 22 20:47:31 myvolumio ntpd[3166]: Soliciting pool server 193.182.111.143 Oct 22 20:47:31 myvolumio ntpd[3166]: Soliciting pool server 84.16.73.33 Oct 22 20:47:31 myvolumio ntpd[3166]: Soliciting pool server 78.46.102.180 Oct 22 20:47:32 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 22 20:47:32 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 22 20:47:32 myvolumio sudo[3203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 20:47:32 myvolumio sudo[3203]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:32 myvolumio sudo[3203]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:32 myvolumio ntpd[3166]: Soliciting pool server 202.112.31.197 Oct 22 20:47:32 myvolumio ntpd[3166]: Soliciting pool server 119.28.206.193 Oct 22 20:47:32 myvolumio ntpd[3166]: Soliciting pool server 193.182.111.142 Oct 22 20:47:32 myvolumio ntpd[3166]: Deleting interface #3 eth0, 192.168.3.135#123, interface stats: received=3, sent=5, dropped=0, active_time=5 secs Oct 22 20:47:32 myvolumio ntpd[3166]: 84.16.67.12 local addr 192.168.3.135 -> Oct 22 20:47:32 myvolumio ntpd[3166]: 202.112.29.82 local addr 192.168.3.135 -> Oct 22 20:47:32 myvolumio ntpd[3166]: 193.182.111.12 local addr 192.168.3.135 -> Oct 22 20:47:32 myvolumio sudo[3206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:32 myvolumio sudo[3206]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:32 myvolumio sudo[3206]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:33 myvolumio ntpd[3166]: Soliciting pool server 116.203.151.74 Oct 22 20:47:33 myvolumio ntpd[3166]: Soliciting pool server 162.159.200.1 Oct 22 20:47:34 myvolumio ntpd[3166]: Soliciting pool server 2001:da8:9000::130 Oct 22 20:47:37 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 22 20:47:37 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 22 20:47:37 myvolumio volumio[856]: info: Refreshing Cached IP Addresses Oct 22 20:47:37 myvolumio sudo[3212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 22 20:47:37 myvolumio sudo[3212]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:37 myvolumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 20:47:37 myvolumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:37 myvolumio sudo[3214]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:37 myvolumio sudo[3212]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:37 myvolumio sudo[3217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 20:47:37 myvolumio sudo[3217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:37 myvolumio sudo[3217]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:37 myvolumio volumio[856]: error: Upnp client error: Error: This socket has been ended by the other party Oct 22 20:47:38 myvolumio volumio[856]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 22 20:47:39 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:40 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 20:47:40 myvolumio volumio[856]: info: Received Get System Info Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 20:47:40 myvolumio volumio[856]: info: Discovery: Getting this device information Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:40 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:40 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:40 myvolumio volumio[856]: info: Listing playlists Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 20:47:40 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 20:47:40 myvolumio volumio[856]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Travis/Where%20You%20Stand/fb1fb7c2-3a1e-4c30-b791-dc6e6cb0aae7.png' Oct 22 20:47:41 myvolumio ifplugd(eth0)[839]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Oct 22 20:47:41 myvolumio dhcpcd[3230]: sending commands to master dhcpcd process Oct 22 20:47:41 myvolumio dhcpcd[737]: control command: /sbin/dhcpcd -k eth0 Oct 22 20:47:41 myvolumio dhcpcd[737]: eth0: removing interface Oct 22 20:47:41 myvolumio ifplugd(eth0)[839]: client: sending commands to master dhcpcd process Oct 22 20:47:41 myvolumio ifplugd(eth0)[839]: Program executed successfully. Oct 22 20:47:41 myvolumio kernel: RTL8208 Fast Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC) Oct 22 20:47:41 myvolumio kernel: r8169 0000:02:00.0 eth0: Link is Down Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 20:47:42 myvolumio volumio[856]: info: Received Get System Info Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 20:47:42 myvolumio volumio[856]: info: Discovery: Getting this device information Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::volumioGetState Oct 22 20:47:42 myvolumio volumio[856]: info: CorePlayQueue::getTrack 30 Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 22 20:47:42 myvolumio sudo[3257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 22 20:47:42 myvolumio sudo[3257]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:42 myvolumio sudo[3257]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio sudo[3262]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3262]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio sudo[3270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:42 myvolumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3270]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 22 20:47:42 myvolumio sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3276]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 20:47:42 myvolumio sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3282]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 22 20:47:42 myvolumio volumio[856]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 22 20:47:42 myvolumio volumio[856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:47:42 myvolumio volumio[856]: Error: connect ETIMEDOUT 66.220.149.32:80 Oct 22 20:47:42 myvolumio volumio[856]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 22 20:47:42 myvolumio volumio[856]: errno: -110, Oct 22 20:47:42 myvolumio volumio[856]: code: 'ETIMEDOUT', Oct 22 20:47:42 myvolumio volumio[856]: syscall: 'connect', Oct 22 20:47:42 myvolumio volumio[856]: address: '66.220.149.32', Oct 22 20:47:42 myvolumio volumio[856]: port: 80 Oct 22 20:47:42 myvolumio volumio[856]: } Oct 22 20:47:42 myvolumio volumio[856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 20:47:42 myvolumio sudo[3286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 20:47:42 myvolumio sudo[3286]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:42 myvolumio sudo[3286]: pam_unix(sudo:session): session closed for user root Oct 22 20:47:42 myvolumio sudo[3288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 22 20:47:42 myvolumio sudo[3288]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 20:47:43 myvolumio sudo[3300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 20:46 Oct 22 20:47:43 myvolumio sudo[3300]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"