Jan 28 08:44:03 volumio volumio[924]: info: CoreCommandRouter::volumioGetState Jan 28 08:44:03 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: info: Retrieving Cloud Streaming UI Jan 28 08:44:04 volumio volumio[924]: info: Getting Tidal Cloud Configuration Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: info: Getting Qobuz Cloud Configuration Jan 28 08:44:04 volumio volumio[924]: info: Asking plugin for UI Config Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: info: Getting Spotify Cloud Configuration Jan 28 08:44:04 volumio volumio[924]: info: Asking plugin for UI Config Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: info: Saving Spotify Acccount Jan 28 08:44:04 volumio volumio[924]: info: Got Tidal Cloud Configuration Jan 28 08:44:04 volumio volumio[924]: info: Got it Jan 28 08:44:04 volumio volumio[924]: info: Got it Jan 28 08:44:04 volumio volumio[924]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 08:44:04 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 08:44:04 volumio volumio[924]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 08:44:05 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand update Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: info: Jan 28 08:44:08 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand update took 11 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: info: Jan 28 08:44:08 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: info: Jan 28 08:44:08 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 7 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 5 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: info: Command Router : Notfying DB Updatetrue Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: info: Command Router : Notfying DB Updatetrue Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 115 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand status took 116 milliseconds Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: info: Command Router : Notfying DB Updatetrue Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand playlistinfo took 98 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand playlistinfo took 68 milliseconds Jan 28 08:44:08 volumio volumio[924]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:08 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":300864,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:08 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:08 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":300864,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:08 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:08 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":300864,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:08 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:08 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:08 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:08 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:08 volumio volumio[924]: info: ------------------------------ 280ms Jan 28 08:44:08 volumio volumio[924]: info: ------------------------------ 277ms Jan 28 08:44:08 volumio volumio[924]: info: ------------------------------ 275ms Jan 28 08:44:09 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 08:44:11 volumio volumio[924]: info: CoreCommandRouter::volumioGetState Jan 28 08:44:11 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:12 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 179. Jan 28 08:44:12 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:12 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:12 volumio upmpdcli[8679]: Could not open config: /tmp/upmpdcli.conf Jan 28 08:44:12 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:44:12 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 08:44:14 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:14 volumio volumio[924]: info: CURURI: playlists Jan 28 08:44:14 volumio volumio[924]: info: Listing playlists Jan 28 08:44:14 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:16 volumio kernel: CIFS: VFS: Autodisabling the use of server inode numbers on \\192.168.1.2\Musique Jan 28 08:44:16 volumio kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Jan 28 08:44:16 volumio kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Jan 28 08:44:16 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:16 volumio volumio[924]: info: CURURI: music-library Jan 28 08:44:16 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:16 volumio volumio[924]: info: MPD Database updated - AlbumList cache refreshed Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: database Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: MPD Database updated - AlbumList cache refreshed Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: database Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: MPD Database updated - AlbumList cache refreshed Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: database Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: Jan 28 08:44:16 volumio volumio[924]: ---------------------------- MPD announces state update: update Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:16 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:16 volumio go-librespot[1436]: time="2026-01-28T08:44:16+01:00" level=trace msg="sent dealer ping" Jan 28 08:44:17 volumio go-librespot[1436]: time="2026-01-28T08:44:17+01:00" level=trace msg="received dealer pong" Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 935 milliseconds Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 936 milliseconds Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 936 milliseconds Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse Jan 28 08:44:17 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 1298 milliseconds Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 1298 milliseconds Jan 28 08:44:17 volumio volumio[924]: info: sendMpdCommand status took 1299 milliseconds Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse Jan 28 08:44:17 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:17 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand status took 1584 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand status took 1585 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand status took 1584 milliseconds Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:18 volumio volumio[924]: info: Command Router : Notfying DB Updatefalse Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::Close All Modals sent Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 646 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 646 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 313 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 313 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 28 08:44:18 volumio volumio[924]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309367,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309367,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309493,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309493,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309618,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":309618,"duration":334,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:18 volumio volumio[924]: verbose: CURRENT POSITION 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:18 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:18 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1831ms Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1832ms Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1829ms Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1830ms Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1828ms Jan 28 08:44:18 volumio volumio[924]: info: ------------------------------ 1827ms Jan 28 08:44:19 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:19 volumio volumio[924]: info: CURURI: music-library/NAS Jan 28 08:44:20 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:20 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:20 volumio volumio[924]: info: CURURI: music-library/NAS/Volumio Jan 28 08:44:20 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:23 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:23 volumio volumio[924]: info: CURURI: music-library/NAS/Volumio/HIGH QUALITY Jan 28 08:44:23 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:27 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 180. Jan 28 08:44:27 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:27 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:27 volumio upmpdcli[8747]: Could not open config: /tmp/upmpdcli.conf Jan 28 08:44:27 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:44:27 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 08:44:32 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::ClearQueue Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::stPlaybackTimer Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::updateTrackBlock Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrackBlock Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::serviceStop Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 1 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::serviceStop Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::stop Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::clearPlayQueue Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::saveQueue Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::addQueueItems Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::addQueueItems Jan 28 08:44:32 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:32 volumio volumio[924]: info: Adding Item to queue: music-library/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN Jan 28 08:44:32 volumio volumio[924]: info: Exploding uri music-library/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN in service mpd Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F02-rammstein-der_meister-7e148dc2.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/02-rammstein-der_meister-7e148dc2.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F03-rammstein-weisses_fleisch-b7f4d4eb.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/03-rammstein-weisses_fleisch-b7f4d4eb.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F04-rammstein-asche_zu_asche-0c7563f9.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/04-rammstein-asche_zu_asche-0c7563f9.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F05-rammstein-seemann-f369c2c2.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/05-rammstein-seemann-f369c2c2.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F06-rammstein-du_riechst_so_gut-72ee1176.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/06-rammstein-du_riechst_so_gut-72ee1176.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F07-rammstein-das_alte_leid-2af86d53.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/07-rammstein-das_alte_leid-2af86d53.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F08-rammstein-heirate_mich-8f56d57c.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/08-rammstein-heirate_mich-8f56d57c.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F09-rammstein-herzeleid-866ad07b.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/09-rammstein-herzeleid-866ad07b.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F10-rammstein-laichzeit-17eeb450.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/10-rammstein-laichzeit-17eeb450.flac Jan 28 08:44:32 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN%2F11-rammstein-rammstein-037d0d2e.flac&metadata=false Jan 28 08:44:32 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/11-rammstein-rammstein-037d0d2e.flac Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand stop took 51 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::saveQueue Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::updateTrackBlock Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrackBlock Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPlay Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::play index 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::play index undefined Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::clearAddPlayTracks NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 29 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand stop took 14 milliseconds Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand clear took 9 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 7 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 7 milliseconds Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand add "NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac" Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bargain","artist":"The Who","album":"The Who","uri":"NAS/Volumio/DSF/(DSF) 1971 - The Who - Who's Next [SHM SACD DSF][2010]/02 - The Who - Bargain.dsf","trackType":"dsf"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: No code Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: 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 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: No code Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: 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 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: No code Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 139ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 103ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 103ms Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: error: updateQueue error: null Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 105ms Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand add "NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac" took 104 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 8ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 7ms Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand play Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:32 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 68ms Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand play took 67 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 69ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 69ms Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 39 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 38 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 40 milliseconds Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: Jan 28 08:44:32 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 22 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 12 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand status took 10 milliseconds Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:32 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:32 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 100ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 122ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 122ms Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 28 08:44:32 volumio volumio[924]: info: sendMpdCommand playlistinfo took 58 milliseconds Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:32 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:32 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1375 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:32 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:32 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:32 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:32 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 150ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 138ms Jan 28 08:44:32 volumio volumio[924]: info: ------------------------------ 136ms Jan 28 08:44:37 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:44:37 volumio volumio[924]: info: CURURI: music-library/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_ Jan 28 08:44:37 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:37 volumio volumio[924]: info: Preloading song: music-library/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac Jan 28 08:44:37 volumio volumio[924]: info: Exploding uri music-library/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac in service mpd Jan 28 08:44:37 volumio volumio[924]: info: ALBUMART /albumart?cacheid=654&web=//extralarge&path=%2Fmnt%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FScorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_%2FScorpions%20-%20Face%20The%20Heat.flac&metadata=false Jan 28 08:44:37 volumio volumio[924]: info: URI /mnt/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioSeek Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::seek Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: TRACKBLOCK {"uri":"mnt/NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","service":"mpd","name":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=654&web=Rammstein/Herzeleid%20(XXV%20Anniversary%20Edition)/extralarge&path=%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FRammstein-Herzeleid%20XXV%20Anniversary%20Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN&metadata=false","duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::seek Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: error: null Jan 28 08:44:39 volumio volumio[924]: info: Jan 28 08:44:39 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:39 volumio volumio[924]: info: Jan 28 08:44:39 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:39 volumio volumio[924]: info: Jan 28 08:44:39 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand status took 8 milliseconds Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand status took 7 milliseconds Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand status took 6 milliseconds Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 28 08:44:39 volumio volumio[924]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:39 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1670 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:39 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:39 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1652 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:39 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:39 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26855,"duration":316,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1697 Kbps","isStreaming":false,"title":"Wollt ihr das Bett in Flammen sehen","artist":"Rammstein","album":"Herzeleid (XXV Anniversary Edition)","uri":"NAS/Volumio/HIGH QUALITY/Rammstein-Herzeleid XXV Anniversary Edition-DE-24-44-WEB-FLAC-REMASTERED-2020-OBZEN/01-rammstein-wollt_ihr_das_bett_in_flammen_sehen-2f48e116.flac","trackType":"flac"} Jan 28 08:44:39 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:39 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:39 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:39 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:39 volumio volumio[924]: info: ------------------------------ 103ms Jan 28 08:44:39 volumio volumio[924]: info: ------------------------------ 101ms Jan 28 08:44:39 volumio volumio[924]: info: ------------------------------ 100ms Jan 28 08:44:42 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 181. Jan 28 08:44:42 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:42 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:42 volumio upmpdcli[8777]: Could not open config: /tmp/upmpdcli.conf Jan 28 08:44:42 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:44:42 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 08:44:46 volumio go-librespot[1436]: time="2026-01-28T08:44:46+01:00" level=trace msg="sent dealer ping" Jan 28 08:44:47 volumio go-librespot[1436]: time="2026-01-28T08:44:47+01:00" level=trace msg="received dealer pong" Jan 28 08:44:47 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::ClearQueue Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::stPlaybackTimer Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::updateTrackBlock Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrackBlock Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::serviceStop Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::serviceStop Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::stop Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::clearPlayQueue Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::saveQueue Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::addQueueItems Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::addQueueItems Jan 28 08:44:47 volumio volumio[924]: info: Preload queue cleared Jan 28 08:44:47 volumio volumio[924]: info: Adding Item to queue: music-library/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac Jan 28 08:44:47 volumio volumio[924]: info: Using cached record of: music-library/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushQueue Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::saveQueue Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::updateTrackBlock Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrackBlock Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPlay Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::play index 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::play index undefined Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::clearAddPlayTracks NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand stop took 39 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand stop took 9 milliseconds Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand clear took 4 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 3 milliseconds Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand add "NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac" Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: 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 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: No code Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: 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 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: No code Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 59ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 59ms Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: error: updateQueue error: null Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 72ms Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 73 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand add "NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac" took 73 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 17ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 14ms Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand play Jan 28 08:44:47 volumio volumio[924]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 89ms Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces system playlist update Jan 28 08:44:47 volumio volumio[924]: info: Ignoring MPD Status Update Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 34ms Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand play took 23 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 19ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 16ms Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 5 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: Jan 28 08:44:47 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 8 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 9 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 6 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand status took 4 milliseconds Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus stop Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:47 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 31ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 34ms Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 28 08:44:47 volumio volumio[924]: info: sendMpdCommand playlistinfo took 22 milliseconds Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:47 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:47 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:47 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:47 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:47 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:47 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:47 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 73ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 73ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 70ms Jan 28 08:44:47 volumio volumio[924]: info: ------------------------------ 69ms Jan 28 08:44:52 volumio volumio[924]: info: CoreCommandRouter::volumioSeek Jan 28 08:44:52 volumio volumio[924]: info: CoreStateMachine::seek Jan 28 08:44:52 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:52 volumio volumio[924]: info: TRACKBLOCK {"uri":"mnt/NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","service":"mpd","name":"Scorpions - Face The Heat.flac","artist":"","album":"","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=654&web=//extralarge&path=%2FNAS%2FVolumio%2FHIGH%20QUALITY%2FScorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_&metadata=false","duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Jan 28 08:44:52 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer Jan 28 08:44:52 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:52 volumio volumio[924]: info: ControllerMpd::seek Jan 28 08:44:52 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:52 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:52 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 08:44:52 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: error: null Jan 28 08:44:53 volumio volumio[924]: info: Jan 28 08:44:53 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:53 volumio volumio[924]: info: Jan 28 08:44:53 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:53 volumio volumio[924]: info: Jan 28 08:44:53 volumio volumio[924]: ---------------------------- MPD announces state update: player Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::getState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand status Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand status took 5 milliseconds Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand status took 7 milliseconds Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand status took 5 milliseconds Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseState Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 08:44:53 volumio volumio[924]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:53 volumio volumio[924]: verbose: ControllerMpd::parseTrackInfo Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257000,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:53 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:53 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257000,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:53 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:53 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: ControllerMpd::pushState Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::servicePushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257000,"duration":2790,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4615 Kbps","isStreaming":false,"title":"Scorpions - Face The Heat.flac","artist":null,"album":null,"uri":"NAS/Volumio/HIGH QUALITY/Scorpions-Face_The_Heat-LP-(DE)-24BIT-FLAC-1993-REETKEVER.par2_/Scorpions - Face The Heat.flac","trackType":"flac"} Jan 28 08:44:53 volumio volumio[924]: verbose: CURRENT POSITION 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState stateService play Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::syncState currentStatus play Jan 28 08:44:53 volumio volumio[924]: info: Received an update from plugin. extracting info from payload Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: CoreStateMachine::pushState Jan 28 08:44:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 Jan 28 08:44:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState Jan 28 08:44:53 volumio volumio[924]: info: ------------------------------ 79ms Jan 28 08:44:53 volumio volumio[924]: info: ------------------------------ 79ms Jan 28 08:44:53 volumio volumio[924]: info: ------------------------------ 77ms Jan 28 08:44:57 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 182. Jan 28 08:44:57 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:57 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 28 08:44:57 volumio upmpdcli[8823]: Could not open config: /tmp/upmpdcli.conf Jan 28 08:44:57 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 28 08:44:57 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 28 08:45:01 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:45:01 volumio volumio[924]: info: CURURI: music-library Jan 28 08:45:01 volumio volumio[924]: info: Preload queue cleared Jan 28 08:45:03 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 08:45:03 volumio volumio[924]: info: CURURI: artists:// Jan 28 08:45:03 volumio volumio[924]: info: Preload queue cleared Jan 28 08:45:09 volumio volumio[924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 08:45:09 volumio volumio[924]: AggregateError Jan 28 08:45:09 volumio volumio[924]: at internalConnectMultiple (node:net:1114:18) Jan 28 08:45:09 volumio volumio[924]: at afterConnectMultiple (node:net:1667:5) { Jan 28 08:45:09 volumio volumio[924]: code: 'ECONNREFUSED', Jan 28 08:45:09 volumio volumio[924]: [errors]: [ Jan 28 08:45:09 volumio volumio[924]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 08:45:09 volumio volumio[924]: at createConnectionError (node:net:1634:14) Jan 28 08:45:09 volumio volumio[924]: at afterConnectMultiple (node:net:1664:40) { Jan 28 08:45:09 volumio volumio[924]: errno: -111, Jan 28 08:45:09 volumio volumio[924]: code: 'ECONNREFUSED', Jan 28 08:45:09 volumio volumio[924]: syscall: 'connect', Jan 28 08:45:09 volumio volumio[924]: address: '158.69.209.125', Jan 28 08:45:09 volumio volumio[924]: port: 443 Jan 28 08:45:09 volumio volumio[924]: }, Jan 28 08:45:09 volumio volumio[924]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 08:45:09 volumio volumio[924]: at createConnectionError (node:net:1634:14) Jan 28 08:45:09 volumio volumio[924]: at afterConnectMultiple (node:net:1664:40) { Jan 28 08:45:09 volumio volumio[924]: errno: -111, Jan 28 08:45:09 volumio volumio[924]: code: 'ECONNREFUSED', Jan 28 08:45:09 volumio volumio[924]: syscall: 'connect', Jan 28 08:45:09 volumio volumio[924]: address: '158.69.210.98', Jan 28 08:45:09 volumio volumio[924]: port: 443 Jan 28 08:45:09 volumio volumio[924]: } Jan 28 08:45:09 volumio volumio[924]: ] Jan 28 08:45:09 volumio volumio[924]: } Jan 28 08:45:09 volumio volumio[924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 08:45:09 volumio sudo[8870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 08:44' Jan 28 08:45:09 volumio sudo[8870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"