-- Logs begin at Sun 2026-03-22 12:17:41 CET, end at Tue 2026-03-24 20:49:38 CET. -- Mar 24 20:48:01 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:06 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:11 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:16 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:21 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:26 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:31 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:36 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:40 volumio-lenny volumio[974]: info: Preload queue cleared Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreStateMachine::ClearQueue Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreStateMachine::stop Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:48:40 volumio-lenny volumio[974]: info: CorePlayQueue::clearPlayQueue Mar 24 20:48:40 volumio-lenny volumio[974]: info: CorePlayQueue::saveQueue Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:48:40 volumio-lenny volumio[974]: info: CoreStateMachine::addQueueItems Mar 24 20:48:40 volumio-lenny volumio[974]: info: CorePlayQueue::addQueueItems Mar 24 20:48:40 volumio-lenny volumio[974]: info: Preload queue cleared Mar 24 20:48:40 volumio-lenny volumio[974]: info: Adding Item to queue: soundcloud/track@trackId=2173814487@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:40 volumio-lenny volumio[974]: info: Exploding uri soundcloud/track@trackId=2173814487@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D in service soundcloud Mar 24 20:48:40 volumio-lenny volumio[974]: info: [soundcloud] explodeUri: soundcloud/track@trackId=2173814487@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:40 volumio-lenny volumio[974]: info: Adding Item to queue: soundcloud/track@trackId=2199199491@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:40 volumio-lenny volumio[974]: info: Exploding uri soundcloud/track@trackId=2199199491@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D in service soundcloud Mar 24 20:48:40 volumio-lenny volumio[974]: info: [soundcloud] explodeUri: soundcloud/track@trackId=2199199491@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:40 volumio-lenny volumio[974]: info: Adding Item to queue: soundcloud/track@trackId=2138509518 Mar 24 20:48:40 volumio-lenny volumio[974]: info: Exploding uri soundcloud/track@trackId=2138509518 in service soundcloud Mar 24 20:48:40 volumio-lenny volumio[974]: info: [soundcloud] explodeUri: soundcloud/track@trackId=2138509518 Mar 24 20:48:40 volumio-lenny volumio[974]: info: Adding Item to queue: soundcloud/track@trackId=2098539300 Mar 24 20:48:40 volumio-lenny volumio[974]: info: Exploding uri soundcloud/track@trackId=2098539300 in service soundcloud Mar 24 20:48:40 volumio-lenny volumio[974]: info: [soundcloud] explodeUri: soundcloud/track@trackId=2098539300 Mar 24 20:48:41 volumio-lenny volumio[974]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2199199491@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:41 volumio-lenny volumio[974]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2173814487@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A2105217283%7D Mar 24 20:48:41 volumio-lenny volumio[974]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2138509518 Mar 24 20:48:41 volumio-lenny volumio[974]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=2098539300 Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushQueue Mar 24 20:48:41 volumio-lenny volumio[974]: info: CorePlayQueue::saveQueue Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::updateTrackBlock Mar 24 20:48:41 volumio-lenny volumio[974]: info: CorePlayQueue::getTrackBlock Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPlay Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::play index 3 Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::stop Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::play index undefined Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 20:48:41 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::startPlaybackTimer Mar 24 20:48:41 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:41 volumio-lenny volumio[974]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2098539300 Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 20:48:41 volumio-lenny volumio[974]: info: sendMpdCommand stop took 4 milliseconds Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 20:48:41 volumio-lenny volumio[974]: info: sendMpdCommand clear took 1 milliseconds Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus" Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: sendMpdCommand addid "https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus" took 10 milliseconds Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 9ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 7ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 6ms Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: MPD COMMAND [object Object] Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: MPD COMMAND [object Object] Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: error: Upnp client error: Error: This socket has been ended by the other party Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 13ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 12ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 43ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 43ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 39ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 20:48:41 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand play Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Mar 24 20:48:41 volumio-lenny volumio[974]: ---------------------------- MPD announces system playlist update Mar 24 20:48:41 volumio-lenny volumio[974]: info: Ignoring MPD Status Update Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 8ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: sendMpdCommand play took 5 milliseconds Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 5ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 4ms Mar 24 20:48:41 volumio-lenny volumio[974]: info: ------------------------------ 4ms Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - ---- read samplerate from file: 48000 Mar 24 20:48:44 volumio-lenny volumio[974]: info: camilladsp stopping service pid 10182... Mar 24 20:48:44 volumio-lenny volumio[974]: info: camilladsp service terminated, instance 1 Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 20:48:44 volumio-lenny volumio[974]: info: camilladsp service started and running in background, instance 1 Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - ---- read samplerate from file: 48000 Mar 24 20:48:44 volumio-lenny volumio[974]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 166 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 165 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 165 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 164 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: Mar 24 20:48:44 volumio-lenny volumio[974]: ---------------------------- MPD announces state update: player Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::getState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand status Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 33 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 8 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 7 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: info: sendMpdCommand status took 6 milliseconds Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::parseState Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus stop Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:44 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:44 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:44 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:44 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:44 volumio-lenny volumio[974]: info: ------------------------------ 234ms Mar 24 20:48:44 volumio-lenny volumio[974]: info: ------------------------------ 252ms Mar 24 20:48:44 volumio-lenny volumio[974]: info: ------------------------------ 252ms Mar 24 20:48:44 volumio-lenny volumio[974]: info: ------------------------------ 252ms Mar 24 20:48:44 volumio-lenny volumio[974]: error: FusionDsp - WebSocket error: [object Object] Mar 24 20:48:45 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 137 milliseconds Mar 24 20:48:45 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 135 milliseconds Mar 24 20:48:45 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 136 milliseconds Mar 24 20:48:45 volumio-lenny volumio[974]: info: sendMpdCommand playlistinfo took 137 milliseconds Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: ControllerMpd::parseTrackInfo Mar 24 20:48:45 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:45 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:45 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:45 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: ControllerMpd::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::servicePushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CorePlayQueue::getTrack 3 Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":4891,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AFFENKÄFIG TIEFUNDTON BOCHUM LIVESET 2025","artist":"Davidburn","album":"SoundCloud Track","uri":"https://cf-hls-opus-media.sndcdn.com/playlist/9eb8e48f-6988-492b-9be5-30dcbe2d68d7.64.opus/playlist.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLWhscy1vcHVzLW1lZGlhLnNuZGNkbi5jb20vcGxheWxpc3QvOWViOGU0OGYtNjk4OC00OTJiLTliZTUtMzBkY2JlMmQ2OGQ3LjY0Lm9wdXMvcGxheWxpc3QubTN1OCoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3NzQzODY3MzJ9fX1dfQ__&Signature=caPfWx3fepQQbkddWpXovpf1dLx50DQktgTdfc2Wjq5CJ7P3OGyDYbwzCvyIToo4MORsV3~2Qg4nxusnvulD3GHfucqWtTlYT1n653z~KpcrDHpZZS2Uw9PFh64Wi77BVf4pfLVg4k~oLudeC4MEQiefaaSf9G2V7Tiw1pT6vTrItlZ7adtfyxoZ59n45x~~qNQ5IiJbaV~tCreNKOejAMAnoBXfh5ns-uDYb3eP~QFjWaistkzUrsEqxSU63wmZNhjO9VDnA3GluVsljLwY3L0dRDEH~m0lsyQJAcavN0lACk1TRHm-2M-0g67MO4FoRCofwO1nSnAdZGxXZ2suuw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ&_vt=.opus","trackType":"opus"} Mar 24 20:48:45 volumio-lenny volumio[974]: verbose: CURRENT POSITION 3 Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState stateService play Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::syncState currentStatus play Mar 24 20:48:45 volumio-lenny volumio[974]: info: Received an update from plugin. extracting info from payload Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreStateMachine::pushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: CoreCommandRouter::volumioPushState Mar 24 20:48:45 volumio-lenny volumio[974]: info: ------------------------------ 294ms Mar 24 20:48:45 volumio-lenny volumio[974]: info: ------------------------------ 268ms Mar 24 20:48:45 volumio-lenny volumio[974]: info: ------------------------------ 268ms Mar 24 20:48:45 volumio-lenny volumio[974]: info: ------------------------------ 268ms Mar 24 20:48:46 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:51 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:53 volumio-lenny volumio[974]: Searching all installed plugins Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 24 20:48:53 volumio-lenny volumio[974]: info: Fehler bei der Kommunikation mit SHOUTCAST Mar 24 20:48:54 volumio-lenny volumio[974]: Searching all installed plugins Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: , search Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Mar 24 20:48:54 volumio-lenny volumio[974]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 24 20:48:54 volumio-lenny volumio[974]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 24 20:48:56 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:48:58 volumio-lenny volumio[974]: error: Search in plugin mpd timed out Mar 24 20:48:58 volumio-lenny volumio[974]: info: All search sources collected, pushing search results Mar 24 20:48:58 volumio-lenny volumio[974]: Upnp client error: Error: This socket has been ended by the other party Mar 24 20:48:58 volumio-lenny volumio[974]: Upnp client error: Error: This socket has been ended by the other party Mar 24 20:48:59 volumio-lenny volumio[974]: error: Search in plugin mpd timed out Mar 24 20:48:59 volumio-lenny volumio[974]: error: Search in plugin soundcloud timed out Mar 24 20:48:59 volumio-lenny volumio[974]: info: All search sources collected, pushing search results Mar 24 20:49:02 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:49:08 volumio-lenny volumio[974]: info: Discovery: Restarting Browsing Mar 24 20:49:11 volumio-lenny volumio[974]: <--- Last few GCs ---> Mar 24 20:49:11 volumio-lenny volumio[974]: [974:0x440d780] 696169745 ms: Scavenge 173.1 (176.9) -> 173.1 (177.2) MB, 5.9 / 0.0 ms (average mu = 0.945, current mu = 0.000) allocation failure Mar 24 20:49:11 volumio-lenny volumio[974]: [974:0x440d780] 696170470 ms: Mark-sweep (reduce) 173.1 (177.2) -> 166.6 (170.8) MB, 725.3 / 4.2 ms (average mu = 0.897, current mu = 0.082) last resort GC in old space requested Mar 24 20:49:11 volumio-lenny volumio[974]: [974:0x440d780] 696171232 ms: Mark-sweep (reduce) 166.6 (169.3) -> 166.5 (169.5) MB, 761.7 / 4.8 ms (average mu = 0.809, current mu = 0.000) last resort GC in old space requested Mar 24 20:49:11 volumio-lenny volumio[974]: <--- JS stacktrace ---> Mar 24 20:49:11 volumio-lenny volumio[974]: FATAL ERROR: CALL_AND_RETRY_LAST Allocation failed - JavaScript heap out of memory Mar 24 20:49:11 volumio-lenny systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Mar 24 20:49:11 volumio-lenny volumio-remote-updater[603]: [2026-03-24 20:49:11] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 24 20:49:11 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:11.737+01:00 level=ERROR msg="failed reading message" error="read tcp 127.0.0.1:43466->127.0.0.1:3000: read: connection reset by peer" Mar 24 20:49:11 volumio-lenny volumio-remote-updater[603]: [2026-03-24 20:49:11] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 24 20:49:11 volumio-lenny systemd[1]: volumio.service: Failed with result 'signal'. Mar 24 20:49:11 volumio-lenny systemd[1]: Started dynamicswap service. Mar 24 20:49:11 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:11.957+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 24 20:49:12 volumio-lenny systemd[1]: dynamicswap.service: Succeeded. Mar 24 20:49:12 volumio-lenny systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 24 20:49:12 volumio-lenny systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 24 20:49:12 volumio-lenny systemd[1]: Started dynamicswap service. Mar 24 20:49:12 volumio-lenny systemd[1]: Stopped Volumio Backend Module. Mar 24 20:49:12 volumio-lenny systemd[1]: Started Volumio Backend Module. Mar 24 20:49:12 volumio-lenny systemd[1]: dynamicswap.service: Succeeded. Mar 24 20:49:12 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:12.958+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 24 20:49:13 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:13.962+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 24 20:49:14 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:14.964+01:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Mar 24 20:49:16 volumio-lenny volumio[24895]: info: ------------------------------------------- Mar 24 20:49:16 volumio-lenny volumio[24895]: info: ----- Volumio3 ---- Mar 24 20:49:16 volumio-lenny volumio[24895]: info: ------------------------------------------- Mar 24 20:49:16 volumio-lenny volumio[24895]: info: ----- System startup ---- Mar 24 20:49:16 volumio-lenny volumio[24895]: info: ------------------------------------------- Mar 24 20:49:16 volumio-lenny volumio-remote-updater[603]: [2026-03-24 20:49:16] [connect] Successful connection Mar 24 20:49:18 volumio-lenny volumio[24895]: info: MYVOLUMIO Environment detected Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Plugin folders cleanup Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning into folder /volumio/app/plugins/ Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category audio_interface Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category miscellanea Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category music_service Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category plugins.json Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category system_controller Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category user_interface Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning into folder /data/plugins/ Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category audio_interface Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category music_service Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category system_hardware Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Scanning category user_interface Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Plugin folders cleanup completed Mar 24 20:49:18 volumio-lenny volumio[24895]: info: ------------------------------------------- Mar 24 20:49:18 volumio-lenny volumio[24895]: info: ----- Core plugins startup ---- Mar 24 20:49:18 volumio-lenny volumio[24895]: info: ------------------------------------------- Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Loading plugins from folder /volumio/app/plugins/ Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Adding plugin upnp to MyMusic Plugins Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Loading plugins from folder /data/plugins/ Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Loading plugin "system"... Mar 24 20:49:18 volumio-lenny volumio[24895]: info: Loading plugin "appearance"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "network"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Refreshing Cached IP Addresses Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "services"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "volumio5onboarding"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "alsa_controller"... Mar 24 20:49:20 volumio-lenny sudo[24936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 24 20:49:20 volumio-lenny sudo[24923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 20:49:20 volumio-lenny sudo[24936]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:20 volumio-lenny sudo[24925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 20:49:20 volumio-lenny sudo[24923]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:20 volumio-lenny sudo[24925]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:20 volumio-lenny sudo[24925]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:20 volumio-lenny sudo[24923]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:20 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "wizard"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "networkfs"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Starting Udev Watcher for removable devices Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Ignoring mount for partition: boot Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Ignoring mount for partition: volumio Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Ignoring mount for partition: volumio_data Mar 24 20:49:20 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "volumio_command_line_client"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "upnp"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: [1774381760840] Starting Upmpd Daemon Mar 24 20:49:20 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "my_music"... Mar 24 20:49:20 volumio-lenny volumio[24895]: info: Loading plugin "mpd"... Mar 24 20:49:21 volumio-lenny volumio[24895]: info: Loading plugin "upnp_browser"... Mar 24 20:49:21 volumio-lenny volumio[24895]: info: Starting UPNP Browser Mar 24 20:49:21 volumio-lenny volumio[24895]: info: Loading plugin "alarm-clock"... Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Alarm: Scheduling Playlist Sunshine Live at 6:0 Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Alarm: Scheduling Playlist sl techno at 5:50 Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Alarm: Scheduling Playlist Playlist does not exist at 6:35 Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "airplay_emulation"... Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Starting Shairport Sync Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "last_100"... Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "webradio"... Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "i2s_dacs"... Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "volumiodiscovery"... Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** For more information see Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 20:49:22 volumio-lenny volumio[24895]: *** WARNING *** For more information see Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** For more information see Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 24 20:49:22 volumio-lenny node[24895]: *** WARNING *** For more information see Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Discovery: Started advertising with name: Volumio-Lenny Mar 24 20:49:22 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 24 20:49:22 volumio-lenny volumio[24895]: info: Loading plugin "soundcloud"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "outputs"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "albumart"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Plugin example_plugin is not enabled Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "inputs"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "updater_comm"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Plugin mpdemulation is not enabled Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "rest_api"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "websocket"... Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Starting Socket.io Server version 2.3.0 Mar 24 20:49:23 volumio-lenny volumio[24895]: info: Loading plugin "fusiondsp"... Mar 24 20:49:23 volumio-lenny volumio[24895]: Forking 3 albumart workers Mar 24 20:49:24 volumio-lenny sudo[24936]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:24 volumio-lenny volumio[24895]: info: Applying required configuration parameters for plugin fusiondsp Mar 24 20:49:24 volumio-lenny volumio[24895]: info: Loading plugin "ir_controller"... Mar 24 20:49:25 volumio-lenny volumio[24895]: Starting albumart workers Mar 24 20:49:25 volumio-lenny volumio[24895]: info: Applying required configuration parameters for plugin ir_controller Mar 24 20:49:25 volumio-lenny volumio[24895]: Starting albumart workers Mar 24 20:49:25 volumio-lenny volumio[24895]: info: Plugin peppymeterbasic is not enabled Mar 24 20:49:25 volumio-lenny volumio[24895]: info: Plugin peppyspectrum is not enabled Mar 24 20:49:25 volumio-lenny volumio[24895]: info: Loading plugin "touch_display"... Mar 24 20:49:25 volumio-lenny volumio[24895]: Starting albumart workers Mar 24 20:49:25 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:25.976+01:00 level=WARN msg="reconnection attempt failed" error="read tcp 127.0.0.1:56180->127.0.0.1:3000: i/o timeout" Mar 24 20:49:26 volumio-lenny volumio[24895]: info: Applying required configuration parameters for plugin touch_display Mar 24 20:49:26 volumio-lenny volumio[24895]: info: Loading i18n strings for locale de Mar 24 20:49:26 volumio-lenny volumio[24895]: Updating browse sources language Mar 24 20:49:26 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::initPlayerControls Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: Express server listening on port 3000 Mar 24 20:49:27 volumio-lenny volumio[24895]: [Metrics] WebUI: 11s 559.21ms Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::resetVolumioState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::getcurrentVolume Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Volumio Network Manager: Network status updated: 2 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 192.168.10.33:3000 from 192.168.10.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 192.168.10.33:3000 from 192.168.10.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 192.168.10.33:3000 from 192.168.10.35 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::pushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioPushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::updateTrackBlock Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrackBlock Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioRetrievevolume Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Received Get System Info Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Discovery: Getting this device information Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:49:27 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Reloading queue from file Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::pushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioPushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Setting Device type: Raspberry PI Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::setRepeat false single undefined Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::pushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioPushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::setRandom false Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreStateMachine::pushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioPushState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Completed loading Core Plugins Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Preparing to generate the ALSA configuration file Mar 24 20:49:27 volumio-lenny volumio[24895]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 24 20:49:27 volumio-lenny volumio[24895]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Reading ALSA contributions from plugins. Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:27 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Discovery: adding f548f9b2-cbf8-4e59-a6f4-c0ac70cc272d Mar 24 20:49:27 volumio-lenny volumio[24895]: info: Discovery: Found device Volumio-Wohnzimmer Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Discovery: Connecting to remote: 192.168.10.35 Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Asound.conf file unchanged, so no further update is needed Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Output device has changed, restarting MPD Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Output device has changed, restarting Shairport Sync Mar 24 20:49:28 volumio-lenny sudo[25026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:28 volumio-lenny sudo[25026]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:28 volumio-lenny sudo[25026]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:28 volumio-lenny sudo[25028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 20:49:28 volumio-lenny sudo[25028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 20:49:28 volumio-lenny volumio[24895]: info: ___________ START PLUGINS ___________ Mar 24 20:49:28 volumio-lenny systemd[1]: Stopping Music Player Daemon... Mar 24 20:49:28 volumio-lenny volumio[24895]: info: ControllerMpd::onStart: Initializing MPD Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Creating MPD Configuration file Mar 24 20:49:28 volumio-lenny sudo[25035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Mar 24 20:49:28 volumio-lenny sudo[25035]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 20:49:28 volumio-lenny volumio[24895]: info: [1774381768240] CoreMusicLibrary::Adding element Medienserver Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:28 volumio-lenny sudo[25037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 20:49:28 volumio-lenny sudo[25037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:28 volumio-lenny volumio[24895]: info: UPNP Browser: Client initialized successfully Mar 24 20:49:28 volumio-lenny sudo[25037]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:28 volumio-lenny sudo[25035]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:28 volumio-lenny sudo[25040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 20:49:28 volumio-lenny sudo[25040]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 20:49:28 volumio-lenny volumio[24895]: info: [1774381768425] CoreMusicLibrary::Adding element Last_100 Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 20:49:28 volumio-lenny volumio[24895]: info: [1774381768431] CoreMusicLibrary::Adding element Webradio Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Initializing BBC Radios Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 24 20:49:28 volumio-lenny volumio[24895]: info: [1774381768548] CoreMusicLibrary::Adding element SoundCloud Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:28 volumio-lenny volumio[24895]: Cannot find translation for source SoundCloud Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Loading i18n strings for locale de Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:28 volumio-lenny volumio[24895]: info: FusionDsp - mixtype--------------------- Software Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Loading i18n strings for locale de Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Loading i18n strings for locale de Mar 24 20:49:28 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Volumio Calling Home Mar 24 20:49:28 volumio-lenny volumio5-onboarding[2024]: time=2026-03-24T20:49:28.703+01:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 24 20:49:28 volumio-lenny volumio[24895]: info: Preparing to generate the ALSA configuration file Mar 24 20:49:29 volumio-lenny sudo[25064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Mar 24 20:49:29 volumio-lenny sudo[25064]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:29 volumio-lenny sudo[25064]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:29 volumio-lenny sudo[25067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 24 20:49:29 volumio-lenny sudo[25067]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:29 volumio-lenny sudo[25069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 24 20:49:29 volumio-lenny sudo[25069]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:29 volumio-lenny sudo[25072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 24 20:49:29 volumio-lenny sudo[25072]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:29 volumio-lenny systemd[1]: Reloading. Mar 24 20:49:29 volumio-lenny volumio-remote-updater[603]: [2026-03-24 20:49:29] [connect] Successful connection Mar 24 20:49:29 volumio-lenny volumio[24895]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 24 20:49:29 volumio-lenny volumio[24895]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 24 20:49:29 volumio-lenny volumio[24895]: info: Reading ALSA contributions from plugins. Mar 24 20:49:29 volumio-lenny volumio[24895]: info: Discovery: adding b24a4b74-ba66-4b30-a59e-ea7f68b2db55 Mar 24 20:49:29 volumio-lenny volumio[24895]: info: Discovery: Found device Volumio-Lenny Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:29 volumio-lenny volumio[24895]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Mar 24 20:49:29 volumio-lenny volumio[24895]: info: Discovery: Connected to remote: 192.168.10.35 Mar 24 20:49:29 volumio-lenny volumio[24895]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Mar 24 20:49:29 volumio-lenny volumio[24895]: info: MPD Permissions set Mar 24 20:49:29 volumio-lenny volumio[24895]: info: MPD Permissions set Mar 24 20:49:29 volumio-lenny volumio-remote-updater[603]: [2026-03-24 20:49:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774381769 101 Mar 24 20:49:29 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Mar 24 20:49:29 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:29 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Volumio called home Mar 24 20:49:30 volumio-lenny volumio[24895]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 24 20:49:30 volumio-lenny sudo[25099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 24 20:49:30 volumio-lenny sudo[25099]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Starting Shairport Sync Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Starting Shairport Sync Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Starting Shairport Sync Mar 24 20:49:30 volumio-lenny sudo[25106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 20:49:30 volumio-lenny sudo[25099]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:30 volumio-lenny sudo[25106]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:30 volumio-lenny sudo[25113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 20:49:30 volumio-lenny sudo[25108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 20:49:30 volumio-lenny sudo[25113]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny sudo[25108]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 192.168.10.33:3000 from 192.168.10.31 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 24 20:49:30 volumio-lenny volumio[24895]: info: ir_controller: LIRC correctly updated. Mar 24 20:49:30 volumio-lenny volumio[24895]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Mar 24 20:49:30 volumio-lenny volumio[24895]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Asound.conf file unchanged, so no further update is needed Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Output device has changed, restarting MPD Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Output device has changed, restarting Shairport Sync Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:30 volumio-lenny sudo[25125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 20:49:30 volumio-lenny sudo[25125]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny sudo[25125]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:30 volumio-lenny sudo[25127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 20:49:30 volumio-lenny sudo[25127]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 20:49:30 volumio-lenny volumio[24895]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 24 20:49:30 volumio-lenny volumio[24895]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Mar 24 20:49:30 volumio-lenny volumio[24895]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 24 20:49:30 volumio-lenny volumio[24895]: info: camilladsp service started and running in background, instance 1 Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:30 volumio-lenny volumio[24895]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Mar 24 20:49:30 volumio-lenny volumio[24895]: info: FusionDsp loaded Mar 24 20:49:30 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:49:30 volumio-lenny sudo[25143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 24 20:49:30 volumio-lenny sudo[25143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:30 volumio-lenny volumio[24895]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Adding Signal Path Element [object Object] Mar 24 20:49:30 volumio-lenny volumio[24895]: info: Adding fusiondspeq DSP Signal Path Element Mar 24 20:49:30 volumio-lenny volumio[24895]: info: FusionDsp - ---- installed callbackRead Mar 24 20:49:30 volumio-lenny volumio[24895]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 24 20:49:31 volumio-lenny volumio[24895]: info: MPD Permissions set Mar 24 20:49:31 volumio-lenny sudo[25155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 20:49:31 volumio-lenny sudo[25155]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:31 volumio-lenny sudo[25155]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:31 volumio-lenny sudo[25153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 20:49:31 volumio-lenny sudo[25153]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:31 volumio-lenny volumio[24895]: error: FusionDsp - WebSocket error: [object Object] Mar 24 20:49:31 volumio-lenny sudo[25153]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:31 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 20:49:31 volumio-lenny volumio[24895]: info: Starting Shairport Sync Mar 24 20:49:31 volumio-lenny sudo[25163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 20:49:31 volumio-lenny sudo[25163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:31 volumio-lenny sudo[25166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 24 20:49:31 volumio-lenny sudo[25166]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:31 volumio-lenny systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 24 20:49:31 volumio-lenny volumio[24895]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 24 20:49:31 volumio-lenny sudo[25169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Mar 24 20:49:31 volumio-lenny sudo[25169]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:31 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 20:49:32 volumio-lenny volumio[24895]: info: Received Get System Info Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 20:49:32 volumio-lenny volumio[24895]: info: Discovery: Getting this device information Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:32 volumio-lenny volumio[24895]: info: Listing playlists Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetQueue Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreStateMachine::getQueue Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CorePlayQueue::getQueue Mar 24 20:49:32 volumio-lenny volumio[24895]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Mar 24 20:49:32 volumio-lenny volumio[24895]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 20:49:32 volumio-lenny volumio[24895]: info: FusionDsp - Bass-FusionDsp Mar 24 20:49:32 volumio-lenny systemd[1]: mpd.service: Succeeded. Mar 24 20:49:32 volumio-lenny systemd[1]: Stopped Music Player Daemon. Mar 24 20:49:32 volumio-lenny sudo[25072]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:32 volumio-lenny sudo[25067]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:32 volumio-lenny systemd[1]: Reloading. Mar 24 20:49:32 volumio-lenny volumio[24895]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 24 20:49:33 volumio-lenny volumio[24895]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 24 20:49:33 volumio-lenny volumio[24895]: info: touch_display: systemctl daemon-reload succeeded. Mar 24 20:49:33 volumio-lenny volumio[24895]: info: Discovery: Browse raised the following error Error: dns service error: unknown Mar 24 20:49:33 volumio-lenny volumio[24895]: info: Discovery: Restarting Browsing Mar 24 20:49:33 volumio-lenny volumio[24895]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 20:49:33 volumio-lenny volumio[24895]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 20:49:33 volumio-lenny systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 24 20:49:33 volumio-lenny sudo[25189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 24 20:49:33 volumio-lenny sudo[25189]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:33 volumio-lenny volumio[24895]: info: FusionDsp - Bass-FusionDsp Mar 24 20:49:33 volumio-lenny volumio[24895]: info: FusionDsp - Bass-FusionDsp Mar 24 20:49:33 volumio-lenny volumio[24895]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 24 20:49:33 volumio-lenny sudo[25069]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:33 volumio-lenny volumio[24895]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 24 20:49:33 volumio-lenny volumio[24895]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 24 20:49:33 volumio-lenny systemd[1]: Stopping Flexible IR remote input/output application support... Mar 24 20:49:33 volumio-lenny sudo[25166]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:33 volumio-lenny sudo[25143]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:33 volumio-lenny lircd[1489]: lircd-0.10.1[1489]: Notice: caught signal Mar 24 20:49:33 volumio-lenny systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 24 20:49:33 volumio-lenny lircd-0.10.1[1489]: Notice: caught signal Mar 24 20:49:33 volumio-lenny volumio[24895]: info: Upmpdcli Daemon Started Mar 24 20:49:34 volumio-lenny systemd[1]: Starting Music Player Daemon... Mar 24 20:49:34 volumio-lenny systemd[1]: shairport-sync.service: Succeeded. Mar 24 20:49:34 volumio-lenny systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 24 20:49:34 volumio-lenny systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Mar 24 20:49:34 volumio-lenny systemd[1]: lircd.service: Succeeded. Mar 24 20:49:34 volumio-lenny systemd[1]: Stopped Flexible IR remote input/output application support. Mar 24 20:49:34 volumio-lenny systemd[1]: irexec.service: Succeeded. Mar 24 20:49:34 volumio-lenny sudo[25189]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny systemd[1]: Starting lircd(8) initialization helper tool... Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: Volumio Kiosk started. Mar 24 20:49:34 volumio-lenny systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 24 20:49:34 volumio-lenny sudo[25113]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny sudo[25163]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny sudo[25108]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny sudo[25106]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny sudo[25191]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 20:49:34 volumio-lenny sudo[25191]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:34 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:34 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:34 volumio-lenny sudo[25191]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:34 volumio-lenny volumio[24895]: info: CoreCommandRouter::volumioGetState Mar 24 20:49:34 volumio-lenny volumio[24895]: info: CorePlayQueue::getTrack 0 Mar 24 20:49:34 volumio-lenny volumio[24895]: info: Shairport-Sync Started Mar 24 20:49:34 volumio-lenny volumio[24895]: Error adding Membership: Error: addMembership EINVAL Mar 24 20:49:34 volumio-lenny volumio[24895]: info: Shairport-Sync Started Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: X display number found: 0 Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: X display number found: 0 Mar 24 20:49:34 volumio-lenny volumio[24895]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 24 20:49:35 volumio-lenny lircd-setup[25196]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Mar 24 20:49:35 volumio-lenny lircd-setup[25196]: parser = configparser.SafeConfigParser() Mar 24 20:49:35 volumio-lenny systemd[1]: lircd-setup.service: Succeeded. Mar 24 20:49:35 volumio-lenny systemd[1]: Started lircd(8) initialization helper tool. Mar 24 20:49:35 volumio-lenny systemd[1]: Starting Flexible IR remote input/output application support... Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: lircd: Opening log, level: Info Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Version: lircd 0.10.1 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: System info: Linux volumio-lenny 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l GNU/Linux Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: Initial device: /dev/lirc0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: [lirc] protocol is enabled Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: Initial device: /dev/lirc0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: [lirc] protocol is enabled Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: driver: default Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Info: lircd: Opening log, level: Info Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: output: /var/run/lirc/lircd Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: nodaemon: 1 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: logfile: syslog Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: immediate-init: 0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: permission: 666 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: driver-options: Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: listen: 0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: connect: (null) Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: userelease: 0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: effective_user: (null) Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: release_suffix: _EVUP Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: allow_simulate: 0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: repeat_max: 600 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: configfile: /etc/lirc/lircd.conf Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Options: dynamic_codes: (null) Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Current driver: default Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Driver API version: 3 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Driver version: 0.10.0 Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: lircd: Opening log, level: Info Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Notice: Using systemd fd Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Warning: Running as root Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: Using systemd fd Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Info: Using remote: odroid. Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Notice: lircd(default) ready, using /var/run/lirc/lircd Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Warning: Running as root Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: Using remote: odroid. Mar 24 20:49:35 volumio-lenny systemd[1]: Started Flexible IR remote input/output application support. Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: lircd(default) ready, using /var/run/lirc/lircd Mar 24 20:49:35 volumio-lenny sudo[25169]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:35 volumio-lenny volumio[24895]: info: ir_controller: systemctl restart lircd.service succeeded. Mar 24 20:49:35 volumio-lenny sudo[25251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Mar 24 20:49:35 volumio-lenny sudo[25251]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 20:49:35 volumio-lenny systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Notice: accepted new client on /var/run/lirc/lircd Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Notice: accepted new client on /var/run/lirc/lircd Mar 24 20:49:35 volumio-lenny lircd[25247]: lircd-0.10.1[25247]: Info: [lirc] protocol is enabled Mar 24 20:49:35 volumio-lenny lircd-0.10.1[25247]: Info: [lirc] protocol is enabled Mar 24 20:49:35 volumio-lenny sudo[25251]: pam_unix(sudo:session): session closed for user root Mar 24 20:49:35 volumio-lenny volumio[24895]: info: ir_controller: systemctl restart irexec.service succeeded. Mar 24 20:49:36 volumio-lenny mpd[25207]: Mar 24 20:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 20:49:37 volumio-lenny volumio[24895]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Mar 24 20:49:37 volumio-lenny volumio[24895]: Searching all installed plugins Mar 24 20:49:37 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 20:49:37 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: , search Mar 24 20:49:37 volumio-lenny volumio[24895]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 24 20:49:37 volumio-lenny volumio[24895]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:49:37 volumio-lenny volumio[24895]: TypeError: Cannot read property 'then' of undefined Mar 24 20:49:37 volumio-lenny volumio[24895]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1596:17) Mar 24 20:49:37 volumio-lenny volumio[24895]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 24 20:49:37 volumio-lenny volumio[24895]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Mar 24 20:49:37 volumio-lenny volumio[24895]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Mar 24 20:49:37 volumio-lenny volumio[24895]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Mar 24 20:49:37 volumio-lenny volumio[24895]: at Socket.emit (events.js:315:20) Mar 24 20:49:37 volumio-lenny volumio[24895]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 24 20:49:37 volumio-lenny volumio[24895]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 24 20:49:37 volumio-lenny volumio[24895]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 20:49:38 volumio-lenny sudo[25263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-24 20:48 Mar 24 20:49:38 volumio-lenny sudo[25263]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"