-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Mon 2025-02-24 11:42:50 GMT. -- Feb 24 11:41:00 rivo volumio[3130]: info: BOOT COMPLETED Feb 24 11:41:01 rivo volumio-remote-updater[2837]: No test mode Feb 24 11:41:01 rivo volumio-remote-updater[2837]: No alpha test mode Feb 24 11:41:01 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 24 11:41:02 rivo volumio[3908]: ..............................................................++++ Feb 24 11:41:02 rivo volumio[3908]: e is 65537 (0x010001) Feb 24 11:41:02 rivo volumio[3908]: writing RSA key Feb 24 11:41:03 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 11:41:07 rivo volumio[3130]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 24 11:41:07 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 24 11:41:07 rivo volumio[3130]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 24 11:41:07 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:07 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:07 rivo volumio[3130]: info: MyVolumio login type: Token Feb 24 11:41:08 rivo sudo[4140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 24 11:41:08 rivo sudo[4140]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 11:41:08 rivo sudo[4140]: pam_unix(sudo:session): session closed for user root Feb 24 11:41:08 rivo sudo[4143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 24 11:41:08 rivo sudo[4143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 11:41:08 rivo sudo[4143]: pam_unix(sudo:session): session closed for user root Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:41:08 rivo volumio[3130]: verbose: New Socket.io Connection to 192.168.1.112 from 192.168.1.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 24 11:41:08 rivo volumio[3130]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::volumioGetVisibleSources Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:08 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 24 11:41:08 rivo volumio[3130]: info: Received Get System Info Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:41:08 rivo volumio[3130]: info: Discovery: Getting this device information Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:08 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:08 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:08 rivo volumio[3130]: info: Listing playlists Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 11:41:08 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 24 11:41:09 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:09 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:09 rivo volumio[3130]: info: MyVolumio token set successfully Feb 24 11:41:09 rivo volumio[3130]: info: MYVOLUMIO: Adding device Feb 24 11:41:09 rivo volumio[3130]: info: MYVOLUMIO: Evaluating Server Feb 24 11:41:10 rivo volumio[3130]: info: MyVolumio status changed Feb 24 11:41:10 rivo volumio[3130]: info: Streaming services startup Feb 24 11:41:10 rivo volumio[3130]: info: Starting Streaming Daemon Feb 24 11:41:10 rivo volumio[3130]: info: Removing browser output: myVolumio user plan is not superstar Feb 24 11:41:10 rivo volumio[3130]: info: Removing audio output: Feb 24 11:41:10 rivo volumio[3130]: info: Stoppping Tunnel 1 Feb 24 11:41:10 rivo sudo[4166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 24 11:41:10 rivo sudo[4166]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 11:41:10 rivo sudo[4166]: pam_unix(sudo:session): session closed for user root Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 11:41:10 rivo volumio[3130]: info: Received Get System Info Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:41:10 rivo volumio[3130]: info: Discovery: Getting this device information Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:10 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 24 11:41:10 rivo sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 24 11:41:10 rivo sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 24 11:41:10 rivo volumio[3130]: error: Cannot start Volumio Streaming Daemon Feb 24 11:41:10 rivo volumio[3130]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 24 11:41:10 rivo volumio[3130]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 24 11:41:10 rivo sudo[4169]: pam_unix(sudo:session): session closed for user root Feb 24 11:41:10 rivo volumio[3130]: info: Remote SSH Stopped Feb 24 11:41:10 rivo volumio[3130]: info: Setting Geolocation for MyVolumio to eu3 Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:41:10 rivo volumio[3130]: info: CoreCommandRouter::volumioGetQueue Feb 24 11:41:10 rivo volumio[3130]: info: CoreStateMachine::getQueue Feb 24 11:41:10 rivo volumio[3130]: info: CorePlayQueue::getQueue Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 11:41:11 rivo volumio[3130]: info: Received Get System Info Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:41:11 rivo volumio[3130]: info: Discovery: Getting this device information Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:11 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:41:11 rivo volumio[3130]: info: Successfully Added MyVolumio device Feb 24 11:41:11 rivo volumio[3130]: info: Executing endpoint metavolumio Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 11:41:11 rivo volumio[3130]: info: Executing endpoint metavolumio Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 11:41:11 rivo volumio[3130]: info: Executing endpoint metavolumio Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 11:41:11 rivo volumio[3130]: info: Updating MyVolumio device info Feb 24 11:41:11 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 11:41:12 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:12 rivo volumio[3130]: info: CURURI: music-library Feb 24 11:41:12 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:14 rivo volumio[3130]: info: Successfully Updated MyVolumio device Feb 24 11:41:14 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:14 rivo volumio[3130]: info: CURURI: music-library/USB Feb 24 11:41:14 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:17 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:17 rivo volumio[3130]: info: CURURI: music-library/USB/SSD_2TB Feb 24 11:41:17 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:18 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 24 11:41:20 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:20 rivo volumio[3130]: info: CURURI: music-library/USB/SSD_2TB/Album Artists A-Z Feb 24 11:41:20 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:22 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:22 rivo volumio[3130]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::ClearQueue Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::stop Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::clearPlayQueue Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::saveQueue Feb 24 11:41:22 rivo volumio[3130]: info: CoreCommandRouter::volumioPushQueue Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::addQueueItems Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::addQueueItems Feb 24 11:41:22 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:22 rivo volumio[3130]: info: Adding Item to queue: music-library/USB/SSD_2TB/Album Artists A-Z/A Feb 24 11:41:22 rivo volumio[3130]: info: Exploding uri music-library/USB/SSD_2TB/Album Artists A-Z/A in service mpd Feb 24 11:41:22 rivo volumio[3130]: error: scanFolder - failure to stat '/mnt/USB/SSD_2TB/Album Artists A-Z/A' Feb 24 11:41:22 rivo volumio[3130]: info: CoreCommandRouter::volumioPushQueue Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::saveQueue Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::updateTrackBlock Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::getTrackBlock Feb 24 11:41:22 rivo volumio[3130]: info: CoreCommandRouter::volumioPlay Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::play index 0 Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::stop Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::play index undefined Feb 24 11:41:22 rivo volumio[3130]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 11:41:22 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:24 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:24 rivo volumio[3130]: info: CURURI: music-library/USB/SSD_2TB/Album Artists A-Z/A Feb 24 11:41:24 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 24 11:41:26 rivo volumio[3130]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 24 11:41:26 rivo volumio[3130]: info: Received Get System Version Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 24 11:41:26 rivo volumio[3130]: info: Received Get System Info Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 24 11:41:26 rivo volumio[3130]: info: Discovery: Getting this device information Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:26 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:26 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 24 11:41:29 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:41:29 rivo volumio[3130]: info: CURURI: music-library/NAS Feb 24 11:41:29 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:41:35 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:41:35 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: Retrieving Cloud Streaming UI Feb 24 11:41:37 rivo volumio[3130]: info: Getting Tidal Cloud Configuration Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: Getting Qobuz Cloud Configuration Feb 24 11:41:37 rivo volumio[3130]: info: Asking plugin for UI Config Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: Getting Spotify Cloud Configuration Feb 24 11:41:37 rivo volumio[3130]: info: Asking plugin for UI Config Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: Saving Spotify Acccount Feb 24 11:41:37 rivo volumio[3130]: info: Got it Feb 24 11:41:37 rivo volumio[3130]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 11:41:37 rivo volumio[3130]: info: Got Tidal Cloud Configuration Feb 24 11:41:37 rivo volumio[3130]: info: Got it Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:37 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 11:41:41 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:42 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: info: Feb 24 11:41:42 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:42 rivo volumio[3130]: info: sendMpdCommand rescan took 16 milliseconds Feb 24 11:41:42 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: info: Feb 24 11:41:42 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:42 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: info: Feb 24 11:41:42 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:42 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:42 rivo volumio[3130]: info: sendMpdCommand status took 21 milliseconds Feb 24 11:41:42 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:42 rivo volumio[3130]: info: Command Router : Notfying DB Updatetrue Feb 24 11:41:44 rivo volumio[3130]: info: MPD Database updated - AlbumList cache refreshed Feb 24 11:41:44 rivo volumio[3130]: info: Feb 24 11:41:44 rivo volumio[3130]: ---------------------------- MPD announces state update: database Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:44 rivo volumio[3130]: info: Feb 24 11:41:44 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 2571 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 2570 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 2567 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 2566 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 2563 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 12 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 11 milliseconds Feb 24 11:41:44 rivo volumio[3130]: info: sendMpdCommand status took 9 milliseconds Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: 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 24 11:41:44 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: No code Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: 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 24 11:41:44 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: No code Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: 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 24 11:41:44 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: No code Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: 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 24 11:41:44 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: No code Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: 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 24 11:41:44 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: No code Feb 24 11:41:44 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:44 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:44 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:44 rivo volumio[3130]: info: ------------------------------ 2949ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 3134ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 3132ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 582ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 584ms Feb 24 11:41:45 rivo volumio[3130]: info: MPD Database updated - AlbumList cache refreshed Feb 24 11:41:45 rivo volumio[3130]: info: Feb 24 11:41:45 rivo volumio[3130]: ---------------------------- MPD announces state update: database Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: Feb 24 11:41:45 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: MPD Database updated - AlbumList cache refreshed Feb 24 11:41:45 rivo volumio[3130]: info: Feb 24 11:41:45 rivo volumio[3130]: ---------------------------- MPD announces state update: database Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: Feb 24 11:41:45 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 26 milliseconds Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 24 milliseconds Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 22 milliseconds Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 19 milliseconds Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 15 milliseconds Feb 24 11:41:45 rivo volumio[3130]: info: sendMpdCommand status took 15 milliseconds Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: 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 24 11:41:45 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: No code Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: 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 24 11:41:45 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: No code Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: 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 24 11:41:45 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: No code Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: 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 24 11:41:45 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: No code Feb 24 11:41:45 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:41:45 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 407ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 409ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 402ms Feb 24 11:41:45 rivo volumio[3130]: info: ------------------------------ 403ms Feb 24 11:41:45 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:49 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:53 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:57 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:41:57 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 24 11:41:58 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 11:42:01 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:02 rivo systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Feb 24 11:42:02 rivo systemd[1]: Reached target Host and Network Name Lookups. Feb 24 11:42:02 rivo systemd[1]: Starting Preprocess NFS configuration... Feb 24 11:42:02 rivo systemd[1]: nfs-config.service: Succeeded. Feb 24 11:42:02 rivo systemd[1]: Started Preprocess NFS configuration. Feb 24 11:42:02 rivo systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Feb 24 11:42:02 rivo systemd[1]: Starting Notify NFS peers of a restart... Feb 24 11:42:02 rivo sm-notify[4358]: Version 1.3.3 starting Feb 24 11:42:02 rivo systemd[1]: rpc-statd-notify.service: Succeeded. Feb 24 11:42:02 rivo systemd[1]: Started Notify NFS peers of a restart. Feb 24 11:42:02 rivo rpc.statd[4360]: Version 1.3.3 starting Feb 24 11:42:02 rivo rpc.statd[4360]: Flags: TI-RPC Feb 24 11:42:02 rivo systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Feb 24 11:42:05 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand rescan Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand rescan took 11 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 9 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 5 milliseconds Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 145ms Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 153 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 152 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 13 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 9 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 9 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 7 milliseconds Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 502ms Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 361ms Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 358ms Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: Feb 24 11:42:06 rivo volumio[3130]: ---------------------------- MPD announces state update: update Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::getState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::sendMpdCommand status Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 107 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 106 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 105 milliseconds Feb 24 11:42:06 rivo volumio[3130]: info: sendMpdCommand status took 103 milliseconds Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: Command Router : Notfying DB Updatefalse Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::Close All Modals sent Feb 24 11:42:06 rivo volumio[3130]: verbose: ControllerMpd::parseState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ControllerMpd::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::servicePushState Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: 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 24 11:42:06 rivo volumio[3130]: verbose: CURRENT POSITION 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState stateService stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::syncState currentStatus stop Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: No code Feb 24 11:42:06 rivo volumio[3130]: info: CoreStateMachine::pushState Feb 24 11:42:06 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:06 rivo volumio[3130]: info: CoreCommandRouter::volumioPushState Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 330ms Feb 24 11:42:06 rivo volumio[3130]: info: ------------------------------ 331ms Feb 24 11:42:09 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:13 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:17 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:21 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 11:42:22 rivo volumio[3130]: info: CoreCommandRouter::volumioGetState Feb 24 11:42:22 rivo volumio[3130]: info: CorePlayQueue::getTrack 0 Feb 24 11:42:39 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:42:39 rivo volumio[3130]: info: CURURI: music-library Feb 24 11:42:39 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:42:41 rivo volumio[3130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 11:42:41 rivo volumio[3130]: info: CURURI: music-library/INTERNAL Feb 24 11:42:41 rivo volumio[3130]: error: Failed LSINFO: null Feb 24 11:42:41 rivo volumio[3130]: info: Preload queue cleared Feb 24 11:42:44 rivo sudo[3946]: pam_unix(sudo:session): session closed for user root Feb 24 11:42:44 rivo volumio[3130]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Feb 24 11:42:49 rivo volumio[3130]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:42:49 rivo volumio[3130]: TypeError: Cannot read property 'replace' of undefined Feb 24 11:42:49 rivo volumio[3130]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Feb 24 11:42:49 rivo volumio[3130]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Feb 24 11:42:49 rivo volumio[3130]: at listOnTimeout (internal/timers.js:557:17) Feb 24 11:42:49 rivo volumio[3130]: at processTimers (internal/timers.js:500:7) Feb 24 11:42:49 rivo volumio[3130]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 11:42:50 rivo sudo[4482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-24 11:41 Feb 24 11:42:50 rivo sudo[4482]: 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="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"