Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetQueue Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreStateMachine::getQueue Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CorePlayQueue::getQueue Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:00 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 09:03:01 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:02 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 09:03:02 music.petrichor.com volumio[17728]: info: CURURI: playlists Oct 16 09:03:02 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:03 music.petrichor.com volumio[17728]: info: Preload queue cleared Oct 16 09:03:06 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 09:03:06 music.petrichor.com volumio[17728]: info: CURURI: music-library Oct 16 09:03:06 music.petrichor.com volumio[17728]: info: Preload queue cleared Oct 16 09:03:11 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 16 09:03:11 music.petrichor.com volumio[17728]: info: CURURI: artists:// Oct 16 09:03:11 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:12 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:12 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:12 music.petrichor.com volumio[17728]: info: Preload queue cleared Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Oct 16 09:03:18 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 3ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":193887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":193887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 12ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 11ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":193887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio 1 - Alles begint bij luisteren","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:18 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:18 music.petrichor.com volumio[17728]: info: ------------------------------ 21ms Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:18 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:21 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:29 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Chuck%20Wagon%20Gang%2C%20The/01e8c2f6-97ca-487d-9d5f-ced90170c8b1.jpg' Oct 16 09:03:31 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:31 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:31 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:35 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Diverse%20artiesten/f245d768-8a0c-47d9-82a6-2af542700dfb.jpg' Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Oct 16 09:03:37 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 1 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 6 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":213685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zijn er nog vragen?","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":213685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zijn er nog vragen?","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 19ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 19ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":213685,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Zijn er nog vragen?","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:37 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:37 music.petrichor.com volumio[17728]: info: ------------------------------ 26ms Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:37 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:41 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:51 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:03:51 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:51 music.petrichor.com volumio[17728]: info: Listing playlists Oct 16 09:03:54 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hairy%20Diamond/1b2e97e8-832b-4a6d-af7b-92c356499e6f.jpg' Oct 16 09:03:54 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Harold%20Melvin%20%26%20The%20Blue%20Notes/e4549cc5-bc0b-4419-b7f3-b9bd039e49f9.jpg' Oct 16 09:03:54 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Harold%20Melvin%20%26%20the%20Blue%20Notes/813bea76-056c-46d1-b232-9f482a0bdb37.jpg' Oct 16 09:03:54 music.petrichor.com volumio[17728]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Harpo/ab3d0b55-cdda-4e69-acfe-db35669cdc3f.jpg' Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces system playlist update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Ignoring MPD Status Update Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Oct 16 09:03:59 music.petrichor.com volumio[17728]: ---------------------------- MPD announces state update: player Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::getState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand status Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 1 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 2ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand status took 2 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseState Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":235082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Babette Moonen","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":235082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Babette Moonen","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 20ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 21ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: ControllerMpd::parseTrackInfo Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ControllerMpd::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::servicePushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CorePlayQueue::getTrack 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: STATE SERVICE {"status":"play","position":0,"seek":235082,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Babette Moonen","artist":"VRT Radio 1","album":null,"uri":"http://icecast.vrtcdn.be/radio1-high.mp3/tunein","trackType":"mp3/tunein"} Oct 16 09:03:59 music.petrichor.com volumio[17728]: verbose: CURRENT POSITION 0 Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState stateService play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::syncState currentStatus play Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: Received an update from plugin. extracting info from payload Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreStateMachine::pushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioPushState Oct 16 09:03:59 music.petrichor.com volumio[17728]: info: ------------------------------ 28ms Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:03:59 music.petrichor.com volumio[17728]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 16 09:04:01 music.petrichor.com volumio[17728]: info: CoreCommandRouter::volumioGetState Oct 16 09:04:03 music.petrichor.com volumio[17728]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:04:03 music.petrichor.com volumio[17728]: TypeError: Cannot read properties of null (reading 'slice') Oct 16 09:04:03 music.petrichor.com volumio[17728]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at Object.onceWrapper (node:events:629:26) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at ClientRequest.emit (node:events:514:28) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at TLSSocket.socketOnData (node:_http_client:535:22) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at TLSSocket.emit (node:events:514:28) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at addChunk (node:internal/streams/readable:343:12) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at readableAddChunk (node:internal/streams/readable:316:9) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at Readable.push (node:internal/streams/readable:253:10) Oct 16 09:04:03 music.petrichor.com volumio[17728]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Oct 16 09:04:03 music.petrichor.com volumio[17728]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 09:04:04 music.petrichor.com sudo[13225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-16 09:03' Oct 16 09:04:04 music.petrichor.com sudo[13225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"