-- Logs begin at Fri 2024-12-27 16:35:36 UTC, end at Sat 2025-01-11 14:28:32 UTC. -- Jan 11 14:27:04 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 14:27:04 opera volumio[1004]: info: CURURI: music-library Jan 11 14:27:10 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 14:27:10 opera volumio[1004]: info: CURURI: music-library/NAS Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: info: Retrieving Cloud Streaming UI Jan 11 14:27:20 opera volumio[1004]: info: Getting Tidal Cloud Configuration Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: info: Getting Qobuz Cloud Configuration Jan 11 14:27:20 opera volumio[1004]: info: Asking plugin for UI Config Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: info: Getting Spotify Cloud Configuration Jan 11 14:27:20 opera volumio[1004]: info: Asking plugin for UI Config Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: error: Could not retrieve plugin music_service spotify Jan 11 14:27:20 opera volumio[1004]: info: Saving Spotify Acccount Jan 11 14:27:20 opera volumio[1004]: info: Got it Jan 11 14:27:20 opera volumio[1004]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 14:27:20 opera volumio[1004]: info: Got Tidal Cloud Configuration Jan 11 14:27:20 opera volumio[1004]: info: Got it Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 14:27:20 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 14:27:22 opera systemd[1]: Started volumio-remote-updater-push.service. Jan 11 14:27:22 opera systemd[1]: Starting Multi-User System. Jan 11 14:27:22 opera systemd[1]: Reached target Multi-User System. Jan 11 14:27:22 opera systemd[1]: Starting Graphical Interface. Jan 11 14:27:22 opera systemd[1]: Reached target Graphical Interface. Jan 11 14:27:22 opera systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 11 14:27:22 opera volumio[1004]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 14:27:22 opera systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 11 14:27:22 opera systemd[1]: Startup finished in 13.621s (kernel) + 2min 6.272s (userspace) = 2min 19.893s. Jan 11 14:27:23 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 11 14:27:23 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand update Jan 11 14:27:23 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:23 opera volumio[1004]: info: Jan 11 14:27:23 opera volumio[1004]: ---------------------------- MPD announces state update: update Jan 11 14:27:23 opera volumio[1004]: info: sendMpdCommand update took 7 milliseconds Jan 11 14:27:23 opera volumio[1004]: info: ControllerMpd::getState Jan 11 14:27:23 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:24 opera volumio[1004]: info: MPD Database updated - AlbumList cache refreshed Jan 11 14:27:24 opera volumio[1004]: info: Jan 11 14:27:24 opera volumio[1004]: ---------------------------- MPD announces state update: database Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:24 opera volumio[1004]: info: Jan 11 14:27:24 opera volumio[1004]: ---------------------------- MPD announces state update: update Jan 11 14:27:24 opera volumio[1004]: info: ControllerMpd::getState Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:24 opera volumio[1004]: info: ControllerMpd::getState Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:24 opera volumio[1004]: info: sendMpdCommand status took 187 milliseconds Jan 11 14:27:24 opera volumio[1004]: info: sendMpdCommand status took 182 milliseconds Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:24 opera volumio[1004]: info: Command Router : Notfying DB Updatefalse Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::Close All Modals sent Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:24 opera volumio[1004]: info: ControllerMpd::pushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::servicePushState Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: 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} Jan 11 14:27:24 opera volumio[1004]: verbose: CURRENT POSITION 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: No code Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: ------------------------------ 248ms Jan 11 14:27:24 opera volumio[1004]: info: sendMpdCommand status took 96 milliseconds Jan 11 14:27:24 opera volumio[1004]: info: sendMpdCommand status took 95 milliseconds Jan 11 14:27:24 opera volumio[1004]: info: sendMpdCommand status took 94 milliseconds Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:24 opera volumio[1004]: info: Command Router : Notfying DB Updatefalse Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::Close All Modals sent Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:24 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:24 opera volumio[1004]: info: ControllerMpd::pushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::servicePushState Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: 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} Jan 11 14:27:24 opera volumio[1004]: verbose: CURRENT POSITION 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: No code Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: ControllerMpd::pushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::servicePushState Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: 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} Jan 11 14:27:24 opera volumio[1004]: verbose: CURRENT POSITION 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState stateService stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: No code Jan 11 14:27:24 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:24 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:24 opera volumio[1004]: info: ------------------------------ 170ms Jan 11 14:27:24 opera volumio[1004]: info: ------------------------------ 170ms Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:24 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 14:27:27 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 14:27:27 opera volumio[1004]: info: CURURI: music-library Jan 11 14:27:27 opera volumio[1004]: error: Failed LSINFO: null Jan 11 14:27:31 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 14:27:31 opera volumio[1004]: info: CURURI: music-library Jan 11 14:27:31 opera volumio[1004]: error: Failed LSINFO: null Jan 11 14:27:35 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 14:27:35 opera volumio[1004]: info: CURURI: albums:// Jan 11 14:27:35 opera volumio[1004]: listAlbums - loading Albums from cache Jan 11 14:27:40 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri Jan 11 14:27:44 opera volumio[1004]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 14:27:44 opera volumio[1004]: info: CoreStateMachine::ClearQueue Jan 11 14:27:44 opera volumio[1004]: info: CoreStateMachine::stop Jan 11 14:27:44 opera volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 14:27:44 opera volumio[1004]: verbose: UNSET VOLATILE Jan 11 14:27:44 opera volumio[1004]: info: CorePlayQueue::clearPlayQueue Jan 11 14:27:44 opera volumio[1004]: info: CorePlayQueue::saveQueue Jan 11 14:27:44 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:44 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:44 opera volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 11 14:27:44 opera volumio[1004]: info: CoreStateMachine::addQueueItems Jan 11 14:27:44 opera volumio[1004]: info: CorePlayQueue::addQueueItems Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track0 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track0 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track1 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track1 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track2 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track2 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track3 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track3 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track4 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track4 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track5 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track5 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track6 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track6 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track7 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track7 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track8 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track8 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track9 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track9 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track10 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track10 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Adding Item to queue: cdaudio/track11 Jan 11 14:27:44 opera volumio[1004]: info: Exploding uri cdaudio/track11 in service cd_controller Jan 11 14:27:44 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:46 opera volumio[1004]: info: CorePlayQueue::saveQueue Jan 11 14:27:46 opera volumio[1004]: info: CoreCommandRouter::volumioPushQueue Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::updateTrackBlock Jan 11 14:27:46 opera volumio[1004]: info: CorePlayQueue::getTrackBlock Jan 11 14:27:46 opera volumio[1004]: info: CoreCommandRouter::volumioPlay Jan 11 14:27:46 opera volumio[1004]: verbose: UNSET VOLATILE Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::play index 0 Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::stop Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 14:27:46 opera volumio[1004]: verbose: UNSET VOLATILE Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::play index undefined Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 14:27:46 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:46 opera volumio[1004]: info: CoreStateMachine::startPlaybackTimer Jan 11 14:27:46 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:46 opera volumio[1004]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 Jan 11 14:27:46 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 14:27:46 opera volumio[1004]: info: sendMpdCommand stop took 3 milliseconds Jan 11 14:27:46 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 14:27:46 opera volumio[1004]: info: Jan 11 14:27:46 opera volumio[1004]: ---------------------------- MPD announces system playlist update Jan 11 14:27:46 opera volumio[1004]: info: Ignoring MPD Status Update Jan 11 14:27:46 opera volumio[1004]: info: sendMpdCommand clear took 4 milliseconds Jan 11 14:27:46 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" Jan 11 14:27:46 opera volumio[1004]: error: updateQueue error: null Jan 11 14:27:46 opera volumio[1004]: info: Jan 11 14:27:46 opera volumio[1004]: ---------------------------- MPD announces system playlist update Jan 11 14:27:46 opera volumio[1004]: info: Ignoring MPD Status Update Jan 11 14:27:46 opera volumio[1004]: info: ------------------------------ 8ms Jan 11 14:27:46 opera volumio[1004]: info: sendMpdCommand add "cdda:///1" took 7 milliseconds Jan 11 14:27:46 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand play Jan 11 14:27:46 opera volumio[1004]: info: ------------------------------ 14ms Jan 11 14:27:46 opera volumio[1004]: info: sendMpdCommand play took 8 milliseconds Jan 11 14:27:53 opera volumio[1004]: info: Jan 11 14:27:53 opera volumio[1004]: ---------------------------- MPD announces state update: player Jan 11 14:27:53 opera volumio[1004]: info: ControllerMpd::getState Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:53 opera volumio[1004]: info: Jan 11 14:27:53 opera volumio[1004]: ---------------------------- MPD announces state update: player Jan 11 14:27:53 opera volumio[1004]: info: sendMpdCommand status took 2 milliseconds Jan 11 14:27:53 opera volumio[1004]: info: ControllerMpd::getState Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand status Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:27:53 opera volumio[1004]: info: sendMpdCommand status took 2 milliseconds Jan 11 14:27:53 opera volumio[1004]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::parseState Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:27:53 opera volumio[1004]: info: ControllerMpd::pushState Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::servicePushState Jan 11 14:27:53 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:53 opera volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":92,"duration":246,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Jan 11 14:27:53 opera volumio[1004]: verbose: CURRENT POSITION 0 Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::syncState currentStatus stop Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:53 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:53 opera volumio[1004]: info: ------------------------------ 17ms Jan 11 14:27:53 opera volumio[1004]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 11 14:27:53 opera volumio[1004]: verbose: ControllerMpd::parseTrackInfo Jan 11 14:27:53 opera volumio[1004]: info: ControllerMpd::pushState Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::servicePushState Jan 11 14:27:53 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:53 opera volumio[1004]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":246,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} Jan 11 14:27:53 opera volumio[1004]: verbose: CURRENT POSITION 0 Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::syncState stateService play Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::syncState currentStatus play Jan 11 14:27:53 opera volumio[1004]: info: Received an update from plugin. extracting info from payload Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:53 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:53 opera volumio[1004]: info: CoreStateMachine::pushState Jan 11 14:27:53 opera volumio[1004]: info: CorePlayQueue::getTrack 0 Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::volumioPushState Jan 11 14:27:53 opera volumio[1004]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 14:27:53 opera volumio[1004]: info: ------------------------------ 32ms Jan 11 14:27:53 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:53 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:27:53 opera volumio[1004]: info: Updating RAAT Signal Path Jan 11 14:28:01 opera sudo[1663]: pam_unix(sudo:session): session closed for user root Jan 11 14:28:01 opera sudo[1658]: pam_unix(sudo:session): session closed for user root Jan 11 14:28:01 opera sudo[1655]: pam_unix(sudo:session): session closed for user root Jan 11 14:28:01 opera volumio[1004]: info: Cannot mount NAS new at system boot, trial number 2 ,retrying in 5 seconds Jan 11 14:28:01 opera volumio[1004]: info: Cannot mount NAS nas at system boot, trial number 2 ,retrying in 5 seconds Jan 11 14:28:01 opera volumio[1004]: info: Cannot mount NAS voice at system boot, trial number 2 ,retrying in 5 seconds Jan 11 14:28:06 opera sudo[2160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.50.215:/new /mnt/NAS/new Jan 11 14:28:06 opera sudo[2160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:28:06 opera sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.50.215:/gudian /mnt/NAS/nas Jan 11 14:28:06 opera sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:28:06 opera sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.50.215:/voice /mnt/NAS/voice Jan 11 14:28:06 opera sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 14:28:18 opera volumio[1004]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 11 14:28:20 opera volumio[1004]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F%20%3F%3F%3F/addc9a59-583a-463f-95ef-1f706fa203e2.jpg' Jan 11 14:28:22 opera volumio[1004]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%3F%3F/0197b4bf-bd4c-4610-b998-a80fd10c8784.jpg' Jan 11 14:28:32 opera volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:28:32 opera volumio[1004]: { Error: connect ETIMEDOUT 31.13.84.34:80 Jan 11 14:28:32 opera volumio[1004]: at Object._errnoException (util.js:1022:11) Jan 11 14:28:32 opera volumio[1004]: at _exceptionWithHostPort (util.js:1044:20) Jan 11 14:28:32 opera volumio[1004]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jan 11 14:28:32 opera volumio[1004]: code: 'ETIMEDOUT', Jan 11 14:28:32 opera volumio[1004]: errno: 'ETIMEDOUT', Jan 11 14:28:32 opera volumio[1004]: syscall: 'connect', Jan 11 14:28:32 opera volumio[1004]: address: '31.13.84.34', Jan 11 14:28:32 opera volumio[1004]: port: 80 } Jan 11 14:28:32 opera volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 14:28:32 opera sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 14:27 Jan 11 14:28:32 opera sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"