-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2025-12-29 22:25:26 GMT. -- Dec 29 22:24:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:24:05 volumio volumio[959]: info: Received Get System Info Dec 29 22:24:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:24:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:24:05 volumio volumio[959]: info: Discovery: Getting this device information Dec 29 22:24:05 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Dec 29 22:24:05 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:05 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: info: Retrieving Cloud Streaming UI Dec 29 22:24:23 volumio volumio[959]: info: Getting Tidal Cloud Configuration Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: info: Getting Qobuz Cloud Configuration Dec 29 22:24:23 volumio volumio[959]: info: Asking plugin for UI Config Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: info: Getting Spotify Cloud Configuration Dec 29 22:24:23 volumio volumio[959]: info: Asking plugin for UI Config Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: info: Saving Spotify Acccount Dec 29 22:24:23 volumio volumio[959]: info: Got Tidal Cloud Configuration Dec 29 22:24:23 volumio volumio[959]: info: Got it Dec 29 22:24:23 volumio volumio[959]: info: Got it Dec 29 22:24:23 volumio volumio[959]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 29 22:24:23 volumio volumio[959]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::volumioGetBrowseSources Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:24:23 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand update Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: info: Dec 29 22:24:25 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand update took 3 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: info: Dec 29 22:24:25 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: info: Dec 29 22:24:25 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 8 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 6 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 6 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 5 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 5 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand status took 3 milliseconds Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand playlistinfo took 55 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 29 22:24:25 volumio volumio[959]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:25 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:25 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:25 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:25 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:25 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:25 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:25 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:25 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:25 volumio volumio[959]: info: ------------------------------ 124ms Dec 29 22:24:25 volumio volumio[959]: info: ------------------------------ 122ms Dec 29 22:24:25 volumio volumio[959]: info: ------------------------------ 121ms Dec 29 22:24:27 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:24:29 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 29 22:24:29 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand rescan Dec 29 22:24:29 volumio volumio[959]: info: sendMpdCommand rescan took 2 milliseconds Dec 29 22:24:31 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:24:35 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:24:35 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 29 22:24:35 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand update Dec 29 22:24:35 volumio volumio[959]: info: sendMpdCommand update took 3 milliseconds Dec 29 22:24:39 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 29 22:24:44 volumio volumio[959]: info: MPD Database updated - AlbumList cache refreshed Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: database Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: MPD Database updated - AlbumList cache refreshed Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: database Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: MPD Database updated - AlbumList cache refreshed Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: database Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: Dec 29 22:24:44 volumio volumio[959]: ---------------------------- MPD announces state update: update Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:44 volumio volumio[959]: info: ControllerMpd::getState Dec 29 22:24:44 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand status Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 500 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 499 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 500 milliseconds Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 703 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 702 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 703 milliseconds Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 986 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand status took 985 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand playlistinfo took 456 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand playlistinfo took 261 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand playlistinfo took 260 milliseconds Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: info: Command Router : Notfying DB Updatetrue Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::Close All Modals sent Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseState Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1042ms Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1043ms Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1042ms Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1042ms Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 29 22:24:45 volumio volumio[959]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: verbose: ControllerMpd::parseTrackInfo Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ControllerMpd::pushState Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::servicePushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3417,"duration":207,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Looking for a Stranger","artist":"Pat Benatar","album":"Greatest Hits","uri":"USB/NAS/Music/Pat Benatar/Looking for a Stranger.mp3","trackType":"mp3"} Dec 29 22:24:45 volumio volumio[959]: verbose: CURRENT POSITION 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState stateService pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::syncState currentStatus pause Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::pushState Dec 29 22:24:45 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:45 volumio volumio[959]: info: CoreCommandRouter::volumioPushState Dec 29 22:24:45 volumio volumio[959]: info: CoreStateMachine::stPlaybackTimer Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1052ms Dec 29 22:24:45 volumio volumio[959]: info: ------------------------------ 1052ms Dec 29 22:24:57 volumio sudo[5041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 29 22:24:57 volumio sudo[5039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 29 22:24:57 volumio sudo[5039]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:24:57 volumio sudo[5041]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 29 22:24:57 volumio sudo[5039]: pam_unix(sudo:session): session closed for user root Dec 29 22:24:57 volumio sudo[5041]: pam_unix(sudo:session): session closed for user root Dec 29 22:24:57 volumio volumio[959]: verbose: New Socket.io Connection to 192.168.1.119 from 192.168.1.249 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::volumioGetVisibleSources Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Dec 29 22:24:57 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::volumioGetQueue Dec 29 22:24:57 volumio volumio[959]: info: CoreStateMachine::getQueue Dec 29 22:24:57 volumio volumio[959]: info: CorePlayQueue::getQueue Dec 29 22:24:57 volumio volumio[959]: info: Listing playlists Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 29 22:24:57 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:24:59 volumio volumio[959]: info: Received Get System Info Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:24:59 volumio volumio[959]: info: Discovery: Getting this device information Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Dec 29 22:24:59 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:24:59 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 22:24:59 volumio volumio[959]: info: CURURI: artists:// Dec 29 22:24:59 volumio volumio[959]: info: Preload queue cleared Dec 29 22:25:00 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 29 22:25:00 volumio volumio[959]: info: Received Get System Info Dec 29 22:25:00 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:25:00 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:25:00 volumio volumio[959]: info: Discovery: Getting this device information Dec 29 22:25:00 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Dec 29 22:25:00 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:25:00 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:25:03 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 29 22:25:03 volumio volumio[959]: info: CURURI: albums:// Dec 29 22:25:03 volumio volumio[959]: info: listAlbums - loading Albums from cache Dec 29 22:25:03 volumio volumio[959]: info: Preload queue cleared Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 29 22:25:15 volumio volumio[959]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 29 22:25:15 volumio volumio[959]: info: Received Get System Version Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 29 22:25:15 volumio volumio[959]: info: Received Get System Info Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 29 22:25:15 volumio volumio[959]: info: Discovery: Getting this device information Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::volumioGetState Dec 29 22:25:15 volumio volumio[959]: info: CorePlayQueue::getTrack 8 Dec 29 22:25:15 volumio volumio[959]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 29 22:25:26 volumio volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 22:25:26 volumio volumio[959]: TypeError: Cannot read property 'slice' of null Dec 29 22:25:26 volumio volumio[959]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 29 22:25:26 volumio volumio[959]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 29 22:25:26 volumio volumio[959]: at Object.onceWrapper (events.js:422:26) Dec 29 22:25:26 volumio volumio[959]: at ClientRequest.emit (events.js:315:20) Dec 29 22:25:26 volumio volumio[959]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Dec 29 22:25:26 volumio volumio[959]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Dec 29 22:25:26 volumio volumio[959]: at TLSSocket.socketOnData (_http_client.js:509:22) Dec 29 22:25:26 volumio volumio[959]: at TLSSocket.emit (events.js:315:20) Dec 29 22:25:26 volumio volumio[959]: at addChunk (internal/streams/readable.js:309:12) Dec 29 22:25:26 volumio volumio[959]: at readableAddChunk (internal/streams/readable.js:284:9) Dec 29 22:25:26 volumio volumio[959]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Dec 29 22:25:26 volumio volumio[959]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 29 22:25:26 volumio volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 22:25:26 volumio sudo[5144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-29 22:24 Dec 29 22:25:26 volumio sudo[5144]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"