-- Logs begin at Wed 2025-02-19 09:22:41 CST, end at Wed 2025-02-19 09:27:58 CST. -- Feb 19 09:26:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 19 09:26:00 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 19 09:26:00 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 09:26:01 volumio volumio-remote-updater[738]: No test mode Feb 19 09:26:01 volumio volumio-remote-updater[738]: No alpha test mode Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 19 09:26:01 volumio systemd[1]: systemd-timedated.service: Succeeded. Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 19 09:26:01 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 19 09:26:01 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand rescan Feb 19 09:26:01 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:01 volumio volumio[1037]: info: Feb 19 09:26:01 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:01 volumio volumio[1037]: info: sendMpdCommand rescan took 9 milliseconds Feb 19 09:26:01 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:01 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:01 volumio volumio[1037]: info: sendMpdCommand status took 8 milliseconds Feb 19 09:26:01 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Feb 19 09:26:01 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:01 volumio volumio-remote-updater[738]: No test mode Feb 19 09:26:01 volumio volumio-remote-updater[738]: No alpha test mode Feb 19 09:26:02 volumio volumio[1037]: info: Command Router : Notfying DB Updatetrue Feb 19 09:26:02 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:02 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:02 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:02 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:02 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:02 volumio volumio[1037]: 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} Feb 19 09:26:02 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:02 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:02 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:02 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:02 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:02 volumio volumio[1037]: info: No code Feb 19 09:26:02 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:02 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:02 volumio volumio[1037]: info: ------------------------------ 249ms Feb 19 09:26:02 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:02 volumio volumio[1037]: info: Feb 19 09:26:02 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:02 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:02 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:02 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 19 09:26:02 volumio volumio[1037]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:02 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 19 09:26:02 volumio volumio[1037]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 19 09:26:02 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 19 09:26:03 volumio volumio[1037]: info: Received Get System Version Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 09:26:03 volumio volumio[1037]: info: Received Get System Info Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 09:26:03 volumio volumio[1037]: info: Discovery: Getting this device information Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:03 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 09:26:03 volumio volumio[1037]: info: MPD Database updated - AlbumList cache refreshed Feb 19 09:26:03 volumio volumio[1037]: info: Feb 19 09:26:03 volumio volumio[1037]: ---------------------------- MPD announces state update: database Feb 19 09:26:03 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:03 volumio volumio[1037]: info: Feb 19 09:26:03 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:03 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:03 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:03 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:03 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:03 volumio volumio[1037]: info: sendMpdCommand status took 1771 milliseconds Feb 19 09:26:03 volumio volumio[1037]: info: sendMpdCommand status took 1771 milliseconds Feb 19 09:26:03 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:03 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:03 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:03 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:03 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:03 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:03 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:03 volumio volumio[1037]: 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} Feb 19 09:26:03 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:03 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:03 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:03 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:03 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:03 volumio volumio[1037]: info: No code Feb 19 09:26:03 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:03 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:03 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 1933ms Feb 19 09:26:04 volumio volumio[1037]: info: MPD Database updated - AlbumList cache refreshed Feb 19 09:26:04 volumio volumio[1037]: info: Feb 19 09:26:04 volumio volumio[1037]: ---------------------------- MPD announces state update: database Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: Feb 19 09:26:04 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: Feb 19 09:26:04 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: Feb 19 09:26:04 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 210 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 207 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 207 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 17 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 15 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 15 milliseconds Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 437ms Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 437ms Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 247ms Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 248ms Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: Feb 19 09:26:04 volumio volumio[1037]: ---------------------------- MPD announces state update: update Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:04 volumio volumio[1037]: info: [1739928364312] Database update started Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 282 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 281 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 278 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 276 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 44 milliseconds Feb 19 09:26:04 volumio volumio[1037]: info: sendMpdCommand status took 42 milliseconds Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: Command Router : Notfying DB Updatefalse Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::Close All Modals sent Feb 19 09:26:04 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: 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} Feb 19 09:26:04 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState stateService stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: No code Feb 19 09:26:04 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 501ms Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 501ms Feb 19 09:26:04 volumio volumio[1037]: info: ------------------------------ 263ms Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 19 09:26:04 volumio volumio[1037]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 19 09:26:04 volumio volumio[1037]: info: Received Get System Version Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 19 09:26:04 volumio volumio[1037]: info: Received Get System Info Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 19 09:26:04 volumio volumio[1037]: info: Discovery: Getting this device information Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:04 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:04 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 19 09:26:06 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 19 09:26:10 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:10 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:14 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:14 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:15 volumio volumio[1037]: info: CoreCommandRouter::volumioGetQueue Feb 19 09:26:15 volumio volumio[1037]: info: CoreStateMachine::getQueue Feb 19 09:26:15 volumio volumio[1037]: info: CorePlayQueue::getQueue Feb 19 09:26:18 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 19 09:26:18 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:20 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 19 09:26:20 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:24 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 19 09:26:25 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:25 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:25 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:28 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 19 09:26:29 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:33 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:33 volumio volumio[1037]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::ClearQueue Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::stop Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::clearPlayQueue Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::saveQueue Feb 19 09:26:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::addQueueItems Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::addQueueItems Feb 19 09:26:33 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:33 volumio volumio[1037]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323 Feb 19 09:26:33 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 19 09:26:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::saveQueue Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Feb 19 09:26:33 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::play index 0 Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::stop Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::play index undefined Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:33 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Feb 19 09:26:33 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:33 volumio volumio[1037]: info: [1739928393743] ControllerWebradio::clearAddPlayTrack Feb 19 09:26:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 09:26:33 volumio volumio[1037]: info: sendMpdCommand stop took 4 milliseconds Feb 19 09:26:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 09:26:33 volumio volumio[1037]: info: Feb 19 09:26:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:33 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:33 volumio volumio[1037]: info: sendMpdCommand clear took 2 milliseconds Feb 19 09:26:33 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Feb 19 09:26:33 volumio volumio[1037]: info: Feb 19 09:26:33 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:33 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:33 volumio volumio[1037]: error: updateQueue error: null Feb 19 09:26:33 volumio volumio[1037]: info: ------------------------------ 5ms Feb 19 09:26:35 volumio volumio[1037]: info: Feb 19 09:26:35 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:35 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:35 volumio volumio[1037]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" took 1442 milliseconds Feb 19 09:26:35 volumio volumio[1037]: info: ------------------------------ 1443ms Feb 19 09:26:35 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 19 09:26:35 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Feb 19 09:26:35 volumio volumio[1037]: info: Feb 19 09:26:35 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:35 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:35 volumio volumio[1037]: info: ------------------------------ 7ms Feb 19 09:26:35 volumio volumio[1037]: info: sendMpdCommand play took 4 milliseconds Feb 19 09:26:35 volumio volumio[1037]: info: ------------------------------ 4ms Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:36 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::ClearQueue Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::stop Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::clearPlayQueue Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::saveQueue Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::addQueueItems Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::addQueueItems Feb 19 09:26:36 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:26:36 volumio volumio[1037]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323 Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushQueue Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::saveQueue Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPlay Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::play index 0 Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::stop Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::play index undefined Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::startPlaybackTimer Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:36 volumio volumio[1037]: info: [1739928396348] ControllerWebradio::clearAddPlayTrack Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand stop took 98 milliseconds Feb 19 09:26:36 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand clear Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:36 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:36 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand status took 3 milliseconds Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand clear took 4 milliseconds Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:36 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:36 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:36 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:36 volumio volumio[1037]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 23ms Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:36 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:36 volumio volumio[1037]: error: updateQueue error: null Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 24ms Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 22 milliseconds Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:36 volumio volumio[1037]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 33ms Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:36 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" took 199 milliseconds Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 180ms Feb 19 09:26:36 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 19 09:26:36 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand play Feb 19 09:26:36 volumio volumio[1037]: info: Feb 19 09:26:36 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:36 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 6ms Feb 19 09:26:36 volumio volumio[1037]: info: sendMpdCommand play took 3 milliseconds Feb 19 09:26:36 volumio volumio[1037]: info: ------------------------------ 3ms Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand status took 60 milliseconds Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:39 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand status took 61 milliseconds Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:39 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:39 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:39 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus stop Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 70ms Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:39 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:39 volumio volumio[1037]: info: Feb 19 09:26:39 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 9ms Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand status took 9 milliseconds Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 3ms Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:39 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:39 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":352,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:39 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:26:39 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 102ms Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 31 milliseconds Feb 19 09:26:39 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 32 milliseconds Feb 19 09:26:39 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:39 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:39 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:39 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:26:39 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:39 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:39 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:39 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:26:39 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:39 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 120ms Feb 19 09:26:39 volumio volumio[1037]: info: ------------------------------ 114ms Feb 19 09:26:47 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:47 volumio sudo[3044]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Feb 19 09:26:47 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:26:49 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:50 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 19 09:26:50 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:51 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 19 09:26:51 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:26:54 volumio volumio[1037]: info: Feb 19 09:26:54 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:54 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:54 volumio volumio[1037]: info: Feb 19 09:26:54 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:54 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:54 volumio volumio[1037]: info: Feb 19 09:26:54 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:26:54 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:26:54 volumio volumio[1037]: info: Feb 19 09:26:54 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:26:54 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:26:54 volumio volumio[1037]: info: ------------------------------ 6ms Feb 19 09:26:54 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Feb 19 09:26:54 volumio volumio[1037]: info: ------------------------------ 4ms Feb 19 09:26:54 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:26:54 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 09:26:54 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:54 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:26:54 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:54 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:54 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:54 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:26:54 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:54 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:26:54 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:26:54 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15492,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:26:54 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:26:54 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:26:54 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:26:54 volumio volumio[1037]: info: ------------------------------ 64ms Feb 19 09:26:54 volumio volumio[1037]: info: ------------------------------ 62ms Feb 19 09:26:58 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root Feb 19 09:27:05 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 19 09:27:05 volumio volumio[1037]: info: CURURI: music-library Feb 19 09:27:05 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:27:09 volumio volumio[1037]: info: Feb 19 09:27:09 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:09 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:09 volumio volumio[1037]: info: Feb 19 09:27:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:09 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:09 volumio volumio[1037]: info: Feb 19 09:27:09 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:09 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:09 volumio volumio[1037]: info: Feb 19 09:27:09 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:09 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:09 volumio volumio[1037]: info: ------------------------------ 9ms Feb 19 09:27:09 volumio volumio[1037]: info: sendMpdCommand status took 10 milliseconds Feb 19 09:27:09 volumio volumio[1037]: info: ------------------------------ 10ms Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:09 volumio volumio[1037]: info: sendMpdCommand status took 10 milliseconds Feb 19 09:27:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:09 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:09 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:09 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:09 volumio volumio[1037]: info: ------------------------------ 54ms Feb 19 09:27:09 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 39 milliseconds Feb 19 09:27:09 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:09 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:09 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:09 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30174,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:09 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:09 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:09 volumio volumio[1037]: info: ------------------------------ 83ms Feb 19 09:27:09 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:09 volumio sudo[3128]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Feb 19 09:27:09 volumio sudo[3128]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:27:10 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 19 09:27:10 volumio volumio[1037]: info: CURURI: music-library/USB Feb 19 09:27:10 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:27:11 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 19 09:27:11 volumio volumio[1037]: info: CURURI: music-library/USB/ボリューム Feb 19 09:27:11 volumio volumio[1037]: error: Failed LSINFO: null Feb 19 09:27:12 volumio volumio[1037]: info: Preload queue cleared Feb 19 09:27:12 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 19 09:27:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:12 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 19 09:27:12 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:17 volumio sudo[3128]: pam_unix(sudo:session): session closed for user root Feb 19 09:27:22 volumio kernel: usb 2-1: USB disconnect, device number 2 Feb 19 09:27:22 volumio volumio[1037]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:14.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-GM32_Best_USB_Device_235678C218CA-0:0-part1 /dev/disk/by-label/boot /dev/disk/by-partlabel/primary /dev/disk/by-uuid/79FB-2BA3 /dev/disk/by-path/pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/be077931-e67a-4f6b-9e97-26d65a674346","DEVNAME":"/dev/sda1","DEVPATH":"/devices/pci0000:00/0000:00:14.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"1","ID_BUS":"usb","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"79FB-2BA3","ID_FS_UUID_ENC":"79FB-2BA3","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Best_USB_Device","ID_MODEL_ENC":"Best\\x20USB\\x20Device","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x4","ID_PART_ENTRY_NAME":"primary","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"350208","ID_PART_ENTRY_TYPE":"c12a7328-f81f-11d2-ba4b-00a0c93ec93b","ID_PART_ENTRY_UUID":"be077931-e67a-4f6b-9e97-26d65a674346","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"cf9a95ce-98b1-4937-a867-70e9f962eba4","ID_PATH":"pci-0000:00:14.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_14_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"GM32_Best_USB_Device_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"GM32","ID_VENDOR_ENC":"GM32\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"primary","SEQNUM":"3801","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3034465"} Feb 19 09:27:22 volumio systemd[1]: Stopped target Local File Systems. Feb 19 09:27:22 volumio systemd[1]: Unmounting /boot... Feb 19 09:27:22 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Feb 19 09:27:22 volumio kernel: FAT-fs (sda1): unable to read boot sector to mark fs as dirty Feb 19 09:27:22 volumio systemd[1]: boot.mount: Succeeded. Feb 19 09:27:22 volumio systemd[1]: Unmounted /boot. Feb 19 09:27:22 volumio systemd[1]: systemd-fsck@dev-disk-by\x2duuid-79FB\x2d2BA3.service: Succeeded. Feb 19 09:27:22 volumio systemd[1]: Stopped File System Check on /dev/disk/by-uuid/79FB-2BA3. Feb 19 09:27:22 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=DID_ERROR driverbyte=DRIVER_OK Feb 19 09:27:23 volumio volumio[1037]: info: Feb 19 09:27:23 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:23 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:23 volumio volumio[1037]: info: Feb 19 09:27:23 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:23 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:23 volumio volumio[1037]: info: Feb 19 09:27:23 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:23 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:23 volumio volumio[1037]: info: Feb 19 09:27:23 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:23 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:23 volumio volumio[1037]: info: ------------------------------ 7ms Feb 19 09:27:23 volumio volumio[1037]: info: sendMpdCommand status took 4 milliseconds Feb 19 09:27:23 volumio volumio[1037]: info: ------------------------------ 3ms Feb 19 09:27:23 volumio volumio[1037]: info: sendMpdCommand status took 2 milliseconds Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:23 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 19 09:27:23 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:23 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:23 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:23 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:23 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44755,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:23 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:23 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:23 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:23 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:23 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44755,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:23 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:23 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:23 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:23 volumio volumio[1037]: info: ------------------------------ 93ms Feb 19 09:27:23 volumio volumio[1037]: info: ------------------------------ 90ms Feb 19 09:27:28 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:28 volumio sudo[3208]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Feb 19 09:27:28 volumio sudo[3208]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 19 09:27:30 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 19 09:27:30 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:31 volumio volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::volumioGetState Feb 19 09:27:31 volumio volumio[1037]: info: Feb 19 09:27:31 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:31 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:31 volumio volumio[1037]: info: Feb 19 09:27:31 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:31 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:31 volumio volumio[1037]: info: Feb 19 09:27:31 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:31 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:31 volumio volumio[1037]: info: Feb 19 09:27:31 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:31 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:31 volumio volumio[1037]: info: ------------------------------ 5ms Feb 19 09:27:31 volumio volumio[1037]: info: sendMpdCommand status took 6 milliseconds Feb 19 09:27:31 volumio volumio[1037]: info: ------------------------------ 6ms Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:31 volumio volumio[1037]: info: sendMpdCommand status took 6 milliseconds Feb 19 09:27:31 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:31 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:31 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:31 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53022,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:31 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:31 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:31 volumio volumio[1037]: info: ------------------------------ 40ms Feb 19 09:27:31 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 33 milliseconds Feb 19 09:27:31 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:31 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:31 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:31 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53022,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"Dance Wave Retro!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:31 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:31 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:31 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:32 volumio volumio[1037]: info: ------------------------------ 86ms Feb 19 09:27:40 volumio volumio[1037]: info: Feb 19 09:27:40 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:40 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:40 volumio volumio[1037]: info: Feb 19 09:27:40 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:40 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:40 volumio volumio[1037]: info: Feb 19 09:27:40 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:40 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:40 volumio volumio[1037]: info: Feb 19 09:27:40 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:40 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:40 volumio volumio[1037]: info: ------------------------------ 12ms Feb 19 09:27:40 volumio volumio[1037]: info: sendMpdCommand status took 8 milliseconds Feb 19 09:27:40 volumio volumio[1037]: info: ------------------------------ 6ms Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:40 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Feb 19 09:27:40 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:40 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:40 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:40 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61189,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:40 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:40 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:40 volumio volumio[1037]: info: ------------------------------ 45ms Feb 19 09:27:40 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 19 09:27:40 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:40 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:40 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:40 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":61189,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:40 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:40 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:40 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:40 volumio volumio[1037]: info: ------------------------------ 77ms Feb 19 09:27:55 volumio volumio[1037]: info: Feb 19 09:27:55 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:55 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:55 volumio volumio[1037]: info: Feb 19 09:27:55 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:55 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:55 volumio volumio[1037]: info: Feb 19 09:27:55 volumio volumio[1037]: ---------------------------- MPD announces system playlist update Feb 19 09:27:55 volumio volumio[1037]: info: Ignoring MPD Status Update Feb 19 09:27:55 volumio volumio[1037]: info: Feb 19 09:27:55 volumio volumio[1037]: ---------------------------- MPD announces state update: player Feb 19 09:27:55 volumio volumio[1037]: info: ControllerMpd::getState Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand status Feb 19 09:27:55 volumio volumio[1037]: info: ------------------------------ 7ms Feb 19 09:27:55 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Feb 19 09:27:55 volumio volumio[1037]: info: ------------------------------ 5ms Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:55 volumio volumio[1037]: info: sendMpdCommand status took 5 milliseconds Feb 19 09:27:55 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::parseState Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:55 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:55 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:55 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76519,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:55 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:55 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:55 volumio volumio[1037]: info: ------------------------------ 57ms Feb 19 09:27:55 volumio volumio[1037]: info: sendMpdCommand playlistinfo took 49 milliseconds Feb 19 09:27:55 volumio volumio[1037]: verbose: ControllerMpd::parseTrackInfo Feb 19 09:27:55 volumio volumio[1037]: info: ControllerMpd::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::servicePushState Feb 19 09:27:55 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:55 volumio volumio[1037]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76519,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Feb 19 09:27:55 volumio volumio[1037]: verbose: CURRENT POSITION 0 Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::syncState stateService play Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::syncState currentStatus play Feb 19 09:27:55 volumio volumio[1037]: info: Received an update from plugin. extracting info from payload Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:55 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:55 volumio volumio[1037]: info: ------------------------------ 100ms Feb 19 09:27:57 volumio volumio[1037]: info: CoreCommandRouter::volumioStop Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::stop Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::stPlaybackTimer Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::updateTrackBlock Feb 19 09:27:57 volumio volumio[1037]: info: CorePlayQueue::getTrackBlock Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::pushState Feb 19 09:27:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:57 volumio volumio[1037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 19 09:27:57 volumio volumio[1037]: info: CoreCommandRouter::volumioPushState Feb 19 09:27:57 volumio volumio[1037]: info: CoreStateMachine::serviceStop Feb 19 09:27:57 volumio volumio[1037]: info: CorePlayQueue::getTrack 0 Feb 19 09:27:57 volumio volumio[1037]: info: CoreCommandRouter::serviceStop Feb 19 09:27:57 volumio volumio[1037]: info: [1739928477671] ControllerWebradio::stop Feb 19 09:27:57 volumio volumio[1037]: verbose: ControllerMpd::sendMpdCommand stop Feb 19 09:27:57 volumio volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 09:27:57 volumio volumio[1037]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Feb 19 09:27:57 volumio volumio[1037]: at Object.readdirSync (fs.js:1048:3) Feb 19 09:27:57 volumio volumio[1037]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Feb 19 09:27:57 volumio volumio[1037]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Feb 19 09:27:57 volumio volumio[1037]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Feb 19 09:27:57 volumio volumio[1037]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Feb 19 09:27:57 volumio volumio[1037]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Feb 19 09:27:57 volumio volumio[1037]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 19 09:27:57 volumio volumio[1037]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Feb 19 09:27:57 volumio volumio[1037]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Feb 19 09:27:57 volumio volumio[1037]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 19 09:27:57 volumio volumio[1037]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 19 09:27:57 volumio volumio[1037]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Feb 19 09:27:57 volumio volumio[1037]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Feb 19 09:27:57 volumio volumio[1037]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Feb 19 09:27:57 volumio volumio[1037]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Feb 19 09:27:57 volumio volumio[1037]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Feb 19 09:27:57 volumio volumio[1037]: errno: -20, Feb 19 09:27:57 volumio volumio[1037]: syscall: 'scandir', Feb 19 09:27:57 volumio volumio[1037]: code: 'ENOTDIR', Feb 19 09:27:57 volumio volumio[1037]: path: '/tmp/peppyrunning' Feb 19 09:27:57 volumio volumio[1037]: } Feb 19 09:27:57 volumio volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 19 09:27:58 volumio sudo[3359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-19 09:26 Feb 19 09:27:58 volumio sudo[3359]: 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"