-- Logs begin at Thu 2025-05-08 09:53:52 UTC, end at Thu 2025-05-08 10:12:07 UTC. -- May 08 10:11:00 volumiohp630 volumio-remote-updater[805]: No test mode May 08 10:11:00 volumiohp630 volumio-remote-updater[805]: No alpha test mode May 08 10:11:00 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 08 10:11:01 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 08 10:11:01 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 08 10:11:01 volumiohp630 volumio[1362]: info: Refreshing Cached IP Addresses May 08 10:11:01 volumiohp630 sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 08 10:11:01 volumiohp630 sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:01 volumiohp630 sudo[2146]: pam_unix(sudo:session): session closed for user root May 08 10:11:01 volumiohp630 sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 10:11:01 volumiohp630 sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:01 volumiohp630 sudo[2149]: pam_unix(sudo:session): session closed for user root May 08 10:11:01 volumiohp630 systemd[1]: upmpdcli.service: Succeeded. May 08 10:11:01 volumiohp630 sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 10:11:01 volumiohp630 sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:01 volumiohp630 sudo[2152]: pam_unix(sudo:session): session closed for user root May 08 10:11:01 volumiohp630 volumio[1362]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 08 10:11:01 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: info: Retrieving Cloud Streaming UI May 08 10:11:02 volumiohp630 volumio[1362]: info: Getting Tidal Cloud Configuration May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: info: Getting Qobuz Cloud Configuration May 08 10:11:02 volumiohp630 volumio[1362]: info: Asking plugin for UI Config May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: info: Getting Spotify Cloud Configuration May 08 10:11:02 volumiohp630 volumio[1362]: info: Asking plugin for UI Config May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: info: Saving Spotify Acccount May 08 10:11:02 volumiohp630 volumio[1362]: info: Got Tidal Cloud Configuration May 08 10:11:02 volumiohp630 volumio[1362]: info: Got it May 08 10:11:02 volumiohp630 volumio[1362]: info: Got it May 08 10:11:02 volumiohp630 volumio[1362]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::getUIConfigOnPlugin May 08 10:11:02 volumiohp630 volumio[1362]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioGetBrowseSources May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand rescan May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand rescan took 4 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 7 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 5 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 4 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 4 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 4 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 3 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 101ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 98ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 98ms May 08 10:11:03 volumiohp630 volumio[1362]: info: MPD Database updated - AlbumList cache refreshed May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: database May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: MPD Database updated - AlbumList cache refreshed May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: database May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: MPD Database updated - AlbumList cache refreshed May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: database May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: May 08 10:11:03 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 20 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 19 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 17 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 7 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 6 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 6 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 5 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 5 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: info: sendMpdCommand status took 5 milliseconds May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: 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} May 08 10:11:03 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: No code May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:03 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:03 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 138ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 139ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 126ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 127ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 125ms May 08 10:11:03 volumiohp630 volumio[1362]: info: ------------------------------ 125ms May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand update May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand update took 1 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 3 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 2 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 2 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 1 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 106ms May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 106ms May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 105ms May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: May 08 10:11:06 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 8 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 9 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 8 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 7 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 7 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: info: sendMpdCommand status took 6 milliseconds May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatefalse May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:06 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: 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} May 08 10:11:06 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: No code May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:06 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 113ms May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 113ms May 08 10:11:06 volumiohp630 volumio[1362]: info: ------------------------------ 112ms May 08 10:11:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:10 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 08 10:11:10 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 08 10:11:10 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:11 volumiohp630 sudo[2200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 08 10:11:11 volumiohp630 sudo[2200]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:11 volumiohp630 sudo[2200]: pam_unix(sudo:session): session closed for user root May 08 10:11:11 volumiohp630 sudo[2203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 08 10:11:11 volumiohp630 sudo[2203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:11 volumiohp630 sudo[2203]: pam_unix(sudo:session): session closed for user root May 08 10:11:11 volumiohp630 sudo[2206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 08 10:11:11 volumiohp630 sudo[2206]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:11 volumiohp630 systemd[1]: Started UPnP Renderer front-end to MPD. May 08 10:11:11 volumiohp630 sudo[2206]: pam_unix(sudo:session): session closed for user root May 08 10:11:11 volumiohp630 volumio[1362]: info: Upmpdcli Daemon Started May 08 10:11:11 volumiohp630 volumio[2208]: writing RSA key May 08 10:11:11 volumiohp630 volumio[1362]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L AX6600.local. May 08 10:11:14 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:18 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:22 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:26 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:30 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:34 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare May 08 10:11:36 volumiohp630 volumio[1362]: info: Adding a new share May 08 10:11:36 volumiohp630 volumio[1362]: info: No correspondence found in configuration for share AX6600音乐 on IP 192.168.5.1 May 08 10:11:36 volumiohp630 volumio[1362]: info: Executing SMB command: smbclient --debuglevel=4 -L 192.168.5.1 -N May 08 10:11:36 volumiohp630 volumio[1362]: info: Negotiated SMB version with AX6600音乐: SMB3_11 May 08 10:11:36 volumiohp630 volumio[1362]: info: Detected device AX6600音乐 with version SMB3_11 May 08 10:11:36 volumiohp630 volumio[1362]: info: Set SMB version 3.11 in CIFS options: vers=3.11 May 08 10:11:36 volumiohp630 sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=root,password=5665140,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.11 //192.168.5.1/AX6600音乐 /mnt/NAS/AX6600音乐 May 08 10:11:36 volumiohp630 sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 10:11:36 volumiohp630 kernel: FS-Cache: Loaded May 08 10:11:36 volumiohp630 kernel: Key type dns_resolver registered May 08 10:11:36 volumiohp630 kernel: Key type cifs.spnego registered May 08 10:11:36 volumiohp630 kernel: Key type cifs.idmap registered May 08 10:11:36 volumiohp630 kernel: CIFS: Attempting to mount //192.168.5.1/AX6600音乐 May 08 10:11:36 volumiohp630 sudo[2316]: pam_unix(sudo:session): session closed for user root May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: info: May 08 10:11:36 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: info: May 08 10:11:36 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: info: May 08 10:11:36 volumiohp630 volumio[1362]: ---------------------------- MPD announces state update: update May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::getState May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::sendMpdCommand status May 08 10:11:36 volumiohp630 volumio[1362]: info: sendMpdCommand status took 3 milliseconds May 08 10:11:36 volumiohp630 volumio[1362]: info: sendMpdCommand status took 4 milliseconds May 08 10:11:36 volumiohp630 volumio[1362]: info: sendMpdCommand status took 3 milliseconds May 08 10:11:36 volumiohp630 volumio[1362]: info: sendMpdCommand status took 2 milliseconds May 08 10:11:36 volumiohp630 volumio[1362]: info: sendMpdCommand status took 1 milliseconds May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:36 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:36 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:36 volumiohp630 volumio[1362]: info: Command Router : Notfying DB Updatetrue May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::Close All Modals sent May 08 10:11:36 volumiohp630 volumio[1362]: verbose: ControllerMpd::parseState May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: 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} May 08 10:11:36 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: No code May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: 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} May 08 10:11:36 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: No code May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: ControllerMpd::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::servicePushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: 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} May 08 10:11:36 volumiohp630 volumio[1362]: verbose: CURRENT POSITION 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState stateService stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::syncState currentStatus stop May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: No code May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreStateMachine::pushState May 08 10:11:36 volumiohp630 volumio[1362]: info: CorePlayQueue::getTrack 0 May 08 10:11:36 volumiohp630 volumio[1362]: info: CoreCommandRouter::volumioPushState May 08 10:11:36 volumiohp630 volumio[1362]: info: ------------------------------ 120ms May 08 10:11:36 volumiohp630 volumio[1362]: info: ------------------------------ 120ms May 08 10:11:36 volumiohp630 volumio[1362]: info: ------------------------------ 120ms May 08 10:11:36 volumiohp630 volumio[1362]: info: [1746699096645] Database update started May 08 10:11:37 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 08 10:11:38 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:42 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:46 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:50 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:51 volumiohp630 nmbd[954]: [2025/05/08 10:11:51.435747, 0] ../source3/nmbd/nmbd_nameregister.c:554(register_name) May 08 10:11:51 volumiohp630 nmbd[954]: [144B blob data] May 08 10:11:51 volumiohp630 nmbd[954]: [2025/05/08 10:11:51.435982, 0] ../source3/nmbd/nmbd_nameregister.c:554(register_name) May 08 10:11:51 volumiohp630 nmbd[954]: [144B blob data] May 08 10:11:51 volumiohp630 nmbd[954]: [2025/05/08 10:11:51.436064, 0] ../source3/nmbd/nmbd_nameregister.c:554(register_name) May 08 10:11:51 volumiohp630 nmbd[954]: [144B blob data] May 08 10:11:54 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:11:58 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:12:02 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:12:06 volumiohp630 volumio[1362]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 08 10:12:07 volumiohp630 volumio[1362]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 10:12:07 volumiohp630 volumio[1362]: Error: connect ETIMEDOUT 31.13.96.208:80 May 08 10:12:07 volumiohp630 volumio[1362]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 08 10:12:07 volumiohp630 volumio[1362]: errno: -110, May 08 10:12:07 volumiohp630 volumio[1362]: code: 'ETIMEDOUT', May 08 10:12:07 volumiohp630 volumio[1362]: syscall: 'connect', May 08 10:12:07 volumiohp630 volumio[1362]: address: '31.13.96.208', May 08 10:12:07 volumiohp630 volumio[1362]: port: 80 May 08 10:12:07 volumiohp630 volumio[1362]: } May 08 10:12:07 volumiohp630 volumio[1362]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 10:12:07 volumiohp630 sudo[2416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-08 10:11 May 08 10:12:07 volumiohp630 sudo[2416]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"