-- Logs begin at Sun 2025-01-12 08:59:42 CST, end at Sun 2025-01-12 09:04:03 CST. -- Jan 12 09:03:02 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:03 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jan 12 09:03:03 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jan 12 09:03:04 wjbvolumio volumio[882]: info: Error browsing device WJBNAS for shares : Error: Command failed: echo volumio | smbclient -L wjbnas.local. Jan 12 09:03:04 wjbvolumio volumio[882]: Unable to initialize messaging context Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand rescan Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand rescan took 5 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: database Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: database Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: database Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: Jan 12 09:03:05 wjbvolumio volumio[882]: ---------------------------- MPD announces state update: update Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 18 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 15 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 14 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 14 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 13 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::getState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 13 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 11 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 10 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 9 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 9 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 8 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 7 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatetrue Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatetrue Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatetrue Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 250ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 247ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 246ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 243ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 243ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 241ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 242ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 245 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: info: sendMpdCommand status took 242 milliseconds Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: Command Router : Notfying DB Updatefalse Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: verbose: ControllerMpd::parseState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ControllerMpd::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::servicePushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: 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 12 09:03:05 wjbvolumio volumio[882]: verbose: CURRENT POSITION 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState stateService stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: No code Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreStateMachine::pushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:05 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioPushState Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 299ms Jan 12 09:03:05 wjbvolumio volumio[882]: info: ------------------------------ 300ms Jan 12 09:03:06 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:10 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:14 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:18 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:22 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:23 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:03:23 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:03:23 wjbvolumio volumio[882]: info: Discovery: Getting this device information Jan 12 09:03:23 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 12 09:03:23 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:23 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:03:26 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:30 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: new high-speed USB device number 2 using ehci-pci Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: New USB device found, idVendor=0781, idProduct=5597, bcdDevice= 1.00 Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: Product: Cruzer Glide 3.0 Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: Manufacturer: SanDisk Jan 12 09:03:47 wjbvolumio kernel: usb 3-1: SerialNumber: 4C530000201025113464 Jan 12 09:03:47 wjbvolumio kernel: usb-storage 3-1:1.0: USB Mass Storage device detected Jan 12 09:03:47 wjbvolumio kernel: scsi host5: usb-storage 3-1:1.0 Jan 12 09:03:48 wjbvolumio kernel: scsi 5:0:0:0: Direct-Access SanDisk Cruzer Glide 3.0 1.00 PQ: 0 ANSI: 6 Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: Attached scsi generic sg2 type 0 Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: [sdc] 30605312 512-byte logical blocks: (15.7 GB/14.6 GiB) Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: [sdc] Write Protect is off Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: [sdc] Mode Sense: 43 00 00 00 Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Jan 12 09:03:48 wjbvolumio kernel: sdc: sdc1 Jan 12 09:03:48 wjbvolumio kernel: sd 5:0:0:0: [sdc] Attached SCSI removable disk Jan 12 09:03:48 wjbvolumio volumio[882]: info: Mounting Device 22B8-C72F Jan 12 09:03:48 wjbvolumio sudo[2063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/22B8-C72F -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 12 09:03:48 wjbvolumio sudo[2063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:03:48 wjbvolumio kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jan 12 09:03:48 wjbvolumio sudo[2063]: pam_unix(sudo:session): session closed for user root Jan 12 09:03:48 wjbvolumio kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 12 09:03:51 wjbvolumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Fish%20Leong/b15844f2-3aa7-4f6b-b894-937f541622d4.jpg' Jan 12 09:03:51 wjbvolumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%3F/270e8ce3-9672-4cd3-afdd-593cd0457af0.jpg' Jan 12 09:03:51 wjbvolumio volumio[882]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans-Eberhard%20Ro%C3%9F/eee9b537-81a3-494c-b4d8-c744f00ec057.jpg' Jan 12 09:03:52 wjbvolumio volumio[882]: verbose: New Socket.io Connection to 192.168.10.12 from 192.168.10.11 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 12 09:03:52 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioGetVisibleSources Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:03:52 wjbvolumio volumio[882]: info: Listing playlists Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioGetQueue Jan 12 09:03:52 wjbvolumio volumio[882]: info: CoreStateMachine::getQueue Jan 12 09:03:52 wjbvolumio volumio[882]: info: CorePlayQueue::getQueue Jan 12 09:03:53 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:03:53 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:03:53 wjbvolumio volumio[882]: info: Discovery: Getting this device information Jan 12 09:03:53 wjbvolumio volumio[882]: info: CoreCommandRouter::volumioGetState Jan 12 09:03:53 wjbvolumio volumio[882]: info: CorePlayQueue::getTrack 0 Jan 12 09:03:53 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:03:55 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 09:03:55 wjbvolumio volumio[882]: info: CURURI: music-library Jan 12 09:03:55 wjbvolumio volumio[882]: error: Failed LSINFO: null Jan 12 09:03:55 wjbvolumio volumio[882]: info: Preload queue cleared Jan 12 09:03:57 wjbvolumio volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 12 09:03:57 wjbvolumio volumio[882]: info: CURURI: music-library Jan 12 09:03:57 wjbvolumio volumio[882]: error: Failed LSINFO: null Jan 12 09:03:57 wjbvolumio volumio[882]: info: Preload queue cleared Jan 12 09:04:03 wjbvolumio volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:04:03 wjbvolumio volumio[882]: Error: connect ETIMEDOUT 128.121.243.77:80 Jan 12 09:04:03 wjbvolumio volumio[882]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 12 09:04:03 wjbvolumio volumio[882]: errno: -110, Jan 12 09:04:03 wjbvolumio volumio[882]: code: 'ETIMEDOUT', Jan 12 09:04:03 wjbvolumio volumio[882]: syscall: 'connect', Jan 12 09:04:03 wjbvolumio volumio[882]: address: '128.121.243.77', Jan 12 09:04:03 wjbvolumio volumio[882]: port: 80 Jan 12 09:04:03 wjbvolumio volumio[882]: } Jan 12 09:04:03 wjbvolumio volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:04:03 wjbvolumio sudo[2109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 09:03 Jan 12 09:04:03 wjbvolumio sudo[2109]: 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"