-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Sat 2025-03-08 07:43:55 JST. -- Mar 08 07:42:00 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 07:42:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 08 07:42:01 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand rescan Mar 08 07:42:01 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:42:01 rpz2vol volumio[832]: info: Mar 08 07:42:01 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:42:01 rpz2vol volumio[832]: info: sendMpdCommand rescan took 8 milliseconds Mar 08 07:42:01 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:42:01 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:42:01 rpz2vol volumio[832]: info: sendMpdCommand status took 7 milliseconds Mar 08 07:42:01 rpz2vol volumio[832]: info: sendMpdCommand status took 3 milliseconds Mar 08 07:42:01 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:42:02 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatetrue Mar 08 07:42:02 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:42:02 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: 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} Mar 08 07:42:02 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: No code Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: ------------------------------ 124ms Mar 08 07:42:02 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:42:02 rpz2vol volumio[832]: info: Mar 08 07:42:02 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:42:02 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:42:02 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:42:02 rpz2vol volumio[832]: info: sendMpdCommand status took 10 milliseconds Mar 08 07:42:02 rpz2vol volumio[832]: info: sendMpdCommand status took 8 milliseconds Mar 08 07:42:02 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:42:02 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:42:02 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:42:02 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: 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} Mar 08 07:42:02 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: No code Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:02 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:02 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:42:02 rpz2vol volumio[832]: info: ------------------------------ 83ms Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:42:03 rpz2vol volumio-remote-updater[516]: No test mode Mar 08 07:42:03 rpz2vol volumio-remote-updater[516]: No alpha test mode Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Mar 08 07:42:03 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 07:42:03 rpz2vol systemd[1]: systemd-timedated.service: Succeeded. Mar 08 07:42:04 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 07:42:04 rpz2vol volumio-remote-updater[516]: No test mode Mar 08 07:42:04 rpz2vol volumio-remote-updater[516]: No alpha test mode Mar 08 07:42:04 rpz2vol volumio[832]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 07:42:04 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 07:42:04 rpz2vol volumio[832]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 07:42:04 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 07:42:07 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 07:42:09 rpz2vol volumio[832]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 07:42:09 rpz2vol volumio[832]: info: Received Get System Version Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 07:42:09 rpz2vol volumio[832]: info: Received Get System Info Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 07:42:09 rpz2vol volumio[832]: info: Discovery: Getting this device information Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::volumioGetState Mar 08 07:42:09 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:09 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 07:42:11 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:42:15 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:42:19 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:42:26 rpz2vol volumio[832]: info: CoreCommandRouter::volumioGetQueue Mar 08 07:42:26 rpz2vol volumio[832]: info: CoreStateMachine::getQueue Mar 08 07:42:26 rpz2vol volumio[832]: info: CorePlayQueue::getQueue Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreCommandRouter::volumioRandom Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreCommandRouter::writePlayerControls Mar 08 07:42:40 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreStateMachine::setRepeat true single false Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:42:40 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 07:42:40 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 07:43:01 rpz2vol volumio[832]: info: Getting Alsa Cards List without I2S DAC Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 08 07:43:01 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 07:43:27 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPlay Mar 08 07:43:27 rpz2vol volumio[832]: info: CoreStateMachine::play index undefined Mar 08 07:43:27 rpz2vol volumio[832]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 07:43:27 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: info: Retrieving Cloud Streaming UI Mar 08 07:43:35 rpz2vol volumio[832]: info: Getting Tidal Cloud Configuration Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: info: Getting Qobuz Cloud Configuration Mar 08 07:43:35 rpz2vol volumio[832]: info: Asking plugin for UI Config Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: info: Getting Spotify Cloud Configuration Mar 08 07:43:35 rpz2vol volumio[832]: info: Asking plugin for UI Config Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: info: Saving Spotify Acccount Mar 08 07:43:35 rpz2vol volumio[832]: info: Got Tidal Cloud Configuration Mar 08 07:43:35 rpz2vol volumio[832]: info: Got it Mar 08 07:43:35 rpz2vol volumio[832]: info: Got it Mar 08 07:43:35 rpz2vol volumio[832]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 07:43:35 rpz2vol volumio[832]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 08 07:43:35 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 07:43:36 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 07:43:36 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 07:43:36 rpz2vol volumio[832]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 07:43:36 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:43:36 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand update Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:38 rpz2vol volumio[832]: info: Mar 08 07:43:38 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:38 rpz2vol volumio[832]: info: sendMpdCommand update took 6 milliseconds Mar 08 07:43:38 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:38 rpz2vol volumio[832]: info: Mar 08 07:43:38 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:38 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:38 rpz2vol volumio[832]: info: sendMpdCommand status took 6 milliseconds Mar 08 07:43:38 rpz2vol volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:38 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:38 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: 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} Mar 08 07:43:38 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: No code Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: ------------------------------ 103ms Mar 08 07:43:38 rpz2vol volumio[832]: info: sendMpdCommand status took 103 milliseconds Mar 08 07:43:38 rpz2vol volumio[832]: info: sendMpdCommand status took 101 milliseconds Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:38 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:38 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:38 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: 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} Mar 08 07:43:38 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: No code Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:38 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:38 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:38 rpz2vol volumio[832]: info: ------------------------------ 172ms Mar 08 07:43:40 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand rescan Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:42 rpz2vol volumio[832]: info: Mar 08 07:43:42 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:42 rpz2vol volumio[832]: info: sendMpdCommand rescan took 4 milliseconds Mar 08 07:43:42 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:42 rpz2vol volumio[832]: info: Mar 08 07:43:42 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:42 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:42 rpz2vol volumio[832]: info: sendMpdCommand status took 6 milliseconds Mar 08 07:43:42 rpz2vol volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:42 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:42 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: 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} Mar 08 07:43:42 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: No code Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: ------------------------------ 79ms Mar 08 07:43:42 rpz2vol volumio[832]: info: sendMpdCommand status took 78 milliseconds Mar 08 07:43:42 rpz2vol volumio[832]: info: sendMpdCommand status took 77 milliseconds Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:42 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:42 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:42 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: 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} Mar 08 07:43:42 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: No code Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:42 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:42 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:42 rpz2vol volumio[832]: info: ------------------------------ 168ms Mar 08 07:43:44 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand update Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:47 rpz2vol volumio[832]: info: Mar 08 07:43:47 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:47 rpz2vol volumio[832]: info: sendMpdCommand update took 4 milliseconds Mar 08 07:43:47 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:47 rpz2vol volumio[832]: info: Mar 08 07:43:47 rpz2vol volumio[832]: ---------------------------- MPD announces state update: update Mar 08 07:43:47 rpz2vol volumio[832]: info: ControllerMpd::getState Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::sendMpdCommand status Mar 08 07:43:47 rpz2vol volumio[832]: info: sendMpdCommand status took 6 milliseconds Mar 08 07:43:47 rpz2vol volumio[832]: info: sendMpdCommand status took 4 milliseconds Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:47 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:47 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: 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} Mar 08 07:43:47 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: No code Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: ------------------------------ 78ms Mar 08 07:43:47 rpz2vol volumio[832]: info: sendMpdCommand status took 76 milliseconds Mar 08 07:43:47 rpz2vol volumio[832]: info: sendMpdCommand status took 76 milliseconds Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:47 rpz2vol volumio[832]: info: Command Router : Notfying DB Updatefalse Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::Close All Modals sent Mar 08 07:43:47 rpz2vol volumio[832]: verbose: ControllerMpd::parseState Mar 08 07:43:47 rpz2vol volumio[832]: info: ControllerMpd::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::servicePushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: 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} Mar 08 07:43:47 rpz2vol volumio[832]: verbose: CURRENT POSITION 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::syncState stateService stop Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::syncState currentStatus stop Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: No code Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreStateMachine::pushState Mar 08 07:43:47 rpz2vol volumio[832]: info: CorePlayQueue::getTrack 0 Mar 08 07:43:47 rpz2vol volumio[832]: info: CoreCommandRouter::volumioPushState Mar 08 07:43:47 rpz2vol volumio[832]: info: ------------------------------ 149ms Mar 08 07:43:48 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:43:52 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 07:43:53 rpz2vol volumio[832]: info: Disabling MyMusic plugin upnp Mar 08 07:43:53 rpz2vol sudo[2577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 08 07:43:53 rpz2vol sudo[2577]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 07:43:53 rpz2vol systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 08 07:43:53 rpz2vol volumio[832]: error: Upnp client error: Error: This socket has been ended by the other party Mar 08 07:43:54 rpz2vol volumio[832]: info: Enabling MyMusic plugin upnp Mar 08 07:43:54 rpz2vol volumio[832]: info: Enabling plugin upnp Mar 08 07:43:54 rpz2vol volumio[832]: info: Loading plugin "upnp"... Mar 08 07:43:54 rpz2vol volumio[832]: info: [1741387434808] Starting Upmpd Daemon Mar 08 07:43:54 rpz2vol volumio[832]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 07:43:54 rpz2vol volumio[832]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 07:43:54 rpz2vol volumio[832]: Error: listen EADDRINUSE: address already in use :::6599 Mar 08 07:43:54 rpz2vol volumio[832]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 08 07:43:54 rpz2vol volumio[832]: at listenInCluster (net.js:1366:12) Mar 08 07:43:54 rpz2vol volumio[832]: at Server.listen (net.js:1452:7) Mar 08 07:43:54 rpz2vol volumio[832]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 08 07:43:54 rpz2vol volumio[832]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 08 07:43:54 rpz2vol volumio[832]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 08 07:43:54 rpz2vol volumio[832]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 08 07:43:54 rpz2vol volumio[832]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 08 07:43:54 rpz2vol volumio[832]: code: 'EADDRINUSE', Mar 08 07:43:54 rpz2vol volumio[832]: errno: -98, Mar 08 07:43:54 rpz2vol volumio[832]: syscall: 'listen', Mar 08 07:43:54 rpz2vol volumio[832]: address: '::', Mar 08 07:43:54 rpz2vol volumio[832]: port: 6599 Mar 08 07:43:54 rpz2vol volumio[832]: } Mar 08 07:43:54 rpz2vol volumio[832]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 07:43:55 rpz2vol sudo[2588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 07:42 Mar 08 07:43:55 rpz2vol sudo[2588]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"