-- Logs begin at Mon 2024-04-01 16:19:03 UTC, end at Mon 2024-04-01 16:22:46 UTC. -- Apr 01 16:21:02 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:06 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:14 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:18 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:26 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand update Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand update took 3 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 4 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 3 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ------------------------------ 388ms Apr 01 16:21:27 volumio volumio[978]: info: ------------------------------ 387ms Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: info: Apr 01 16:21:27 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 21 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 20 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 20 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:27 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:27 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: 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} Apr 01 16:21:27 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: No code Apr 01 16:21:27 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:27 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:27 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:27 volumio volumio[978]: info: ------------------------------ 251ms Apr 01 16:21:27 volumio volumio[978]: info: ------------------------------ 251ms Apr 01 16:21:27 volumio volumio[978]: info: ------------------------------ 250ms Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:27 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:30 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:30 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand rescan Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: info: Apr 01 16:21:30 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:30 volumio volumio[978]: info: sendMpdCommand rescan took 3 milliseconds Apr 01 16:21:30 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: info: Apr 01 16:21:30 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:30 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: info: Apr 01 16:21:30 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:30 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:30 volumio volumio[978]: info: sendMpdCommand status took 3 milliseconds Apr 01 16:21:30 volumio volumio[978]: info: sendMpdCommand status took 2 milliseconds Apr 01 16:21:30 volumio volumio[978]: info: sendMpdCommand status took 1 milliseconds Apr 01 16:21:30 volumio volumio[978]: info: sendMpdCommand status took 1 milliseconds Apr 01 16:21:30 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:31 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:31 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: info: Command Router : Notfying DB Updatetrue Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:31 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:31 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: 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} Apr 01 16:21:31 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: No code Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: 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} Apr 01 16:21:31 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: No code Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: 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} Apr 01 16:21:31 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: No code Apr 01 16:21:31 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:31 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:31 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:31 volumio volumio[978]: info: ------------------------------ 417ms Apr 01 16:21:31 volumio volumio[978]: info: ------------------------------ 416ms Apr 01 16:21:31 volumio volumio[978]: info: ------------------------------ 416ms Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:31 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 01 16:21:38 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Apr 01 16:21:38 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: MPD Database updated - AlbumList cache refreshed Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: database Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: MPD Database updated - AlbumList cache refreshed Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: database Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: MPD Database updated - AlbumList cache refreshed Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: database Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: Apr 01 16:21:39 volumio volumio[978]: ---------------------------- MPD announces state update: update Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::getState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::sendMpdCommand status Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 19 milliseconds Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 425ms Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 425ms Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 437 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 437 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 438 milliseconds Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 514ms Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 514ms Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 529 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 530 milliseconds Apr 01 16:21:39 volumio volumio[978]: info: sendMpdCommand status took 530 milliseconds Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: Command Router : Notfying DB Updatefalse Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::Close All Modals sent Apr 01 16:21:39 volumio volumio[978]: verbose: ControllerMpd::parseState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ControllerMpd::pushState Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::servicePushState Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: 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} Apr 01 16:21:39 volumio volumio[978]: verbose: CURRENT POSITION 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState stateService stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::syncState currentStatus stop Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: No code Apr 01 16:21:39 volumio volumio[978]: info: CoreStateMachine::pushState Apr 01 16:21:39 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Apr 01 16:21:39 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 639ms Apr 01 16:21:39 volumio volumio[978]: info: ------------------------------ 640ms Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:39 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Apr 01 16:21:40 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Apr 01 16:21:40 volumio volumio[978]: info: CoreStateMachine::getQueue Apr 01 16:21:40 volumio volumio[978]: info: CorePlayQueue::getQueue Apr 01 16:21:48 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 01 16:21:48 volumio volumio[978]: info: CURURI: albums:// Apr 01 16:21:48 volumio volumio[978]: info: listAlbums - loading Albums from cache Apr 01 16:21:48 volumio volumio[978]: info: Preload queue cleared Apr 01 16:22:14 volumio wpa_supplicant[856]: wlan0: WPA: Group rekeying completed with 2c:91:ab:5e:f0:05 [GTK=TKIP] Apr 01 16:22:19 volumio go-librespot[1427]: time="2024-04-01T16:22:19Z" level=error msg="did not receive last pong from dealer, 30s passed" Apr 01 16:22:19 volumio go-librespot[1427]: time="2024-04-01T16:22:19Z" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Apr 01 16:22:19 volumio go-librespot[1427]: time="2024-04-01T16:22:19Z" level=debug msg="re-established dealer connection" Apr 01 16:22:19 volumio go-librespot[1427]: time="2024-04-01T16:22:19Z" level=debug msg="received connection id: NjM5ZmMxMjctN2UwMi00MzdhLWFiZTctOGY5YzVlZTZhYTczK2RlYWxlcit0Y3A6Ly9ndWMzLWRlYWxlci1hLWoyYnAuZ3VjMy5zcG90aWZ5Lm5ldDo1NzAwK0IwM0QyMjI5RUY3MEYwRjAwQjREMDZDMkUwNDVBQzg0N0JDNzUxRERFMkRDQzc3RkJDNkM3MDA0QTRDQjIwN0E=" Apr 01 16:22:20 volumio go-librespot[1427]: time="2024-04-01T16:22:20Z" level=debug msg="put connect state because NEW_DEVICE" Apr 01 16:22:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 01 16:22:34 volumio volumio[978]: info: Preload queue cleared Apr 01 16:22:41 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root Apr 01 16:22:41 volumio volumio[978]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Apr 01 16:22:46 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 16:22:46 volumio volumio[978]: TypeError: Cannot read property 'replace' of undefined Apr 01 16:22:46 volumio volumio[978]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Apr 01 16:22:46 volumio volumio[978]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Apr 01 16:22:46 volumio volumio[978]: at listOnTimeout (internal/timers.js:557:17) Apr 01 16:22:46 volumio volumio[978]: at processTimers (internal/timers.js:500:7) Apr 01 16:22:46 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 01 16:22:46 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-01 16:21 Apr 01 16:22:46 volumio sudo[1968]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"