-- Logs begin at Wed 2026-03-04 19:20:36 CET, end at Wed 2026-03-04 19:27:19 CET. -- Mar 04 19:26:03 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState Mar 04 19:26:03 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:05 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Mar 04 19:26:05 volumio volumio[1081]: info: CoreStateMachine::play index undefined Mar 04 19:26:05 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:05 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:05 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Mar 04 19:26:05 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:05 volumio volumio[1081]: info: [1772648765026] ControllerSpotify::clearAddPlayTrack Mar 04 19:26:05 volumio volumio[1081]: info: Sending Spotify command with payload to local API: /player/play Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=debug msg="resolved context of track" uri="spotify:track:0HWjXL4layDbYbCj21uzd8" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0HWjXL4layDbYbCj21uzd8" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0HWjXL4layDbYbCj21uzd8" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=trace msg="emitting websocket event: will_play" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=debug msg="selected format OGG_VORBIS_320 (f37eeeeceb3f845728c322817cc4ffb359e258a2)" uri="spotify:track:0HWjXL4layDbYbCj21uzd8" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=debug msg="requested aes key for file f37eeeeceb3f845728c322817cc4ffb359e258a2, gid: 0HWjXL4layDbYbCj21uzd8" Mar 04 19:26:05 volumio go-librespot[1575]: time="2026-03-04T19:26:05+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0HWjXL4layDbYbCj21uzd8: failed retrieving audio key: failed retrieving aes key with code 1" Mar 04 19:26:05 volumio volumio[1081]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 04 19:26:14 volumio go-librespot[1575]: time="2026-03-04T19:26:14+01:00" level=trace msg="sent dealer ping" Mar 04 19:26:14 volumio go-librespot[1575]: time="2026-03-04T19:26:14+01:00" level=trace msg="received dealer pong" Mar 04 19:26:14 volumio volumio[1081]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 19:26:14 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 19:26:14 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 04 19:26:14 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 04 19:26:15 volumio volumio[1081]: info: Received Get System Version Mar 04 19:26:15 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 19:26:20 volumio volumio[1081]: info: CoreCommandRouter::volumioGetState Mar 04 19:26:20 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:32 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Mar 04 19:26:32 volumio volumio[1081]: info: [1772648792324] [80s80s] handleBrowseUri curUri: 80s80s Mar 04 19:26:32 volumio volumio[1081]: info: Preload queue cleared Mar 04 19:26:34 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Mar 04 19:26:34 volumio volumio[1081]: info: [1772648794073] [80s80s] handleBrowseUri curUri: 80s80s/eighties Mar 04 19:26:34 volumio volumio[1081]: info: [1772648794073] [80s80s] getRadioContent url: eighties Mar 04 19:26:34 volumio volumio[1081]: info: Preload queue cleared Mar 04 19:26:35 volumio volumio[1081]: info: Preload queue cleared Mar 04 19:26:35 volumio volumio[1081]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::ClearQueue Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::stop Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::clearPlayQueue Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::saveQueue Mar 04 19:26:35 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::addQueueItems Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::addQueueItems Mar 04 19:26:35 volumio volumio[1081]: info: Preload queue cleared Mar 04 19:26:35 volumio volumio[1081]: info: Adding Item to queue: webeighties/0 Mar 04 19:26:35 volumio volumio[1081]: info: Exploding uri webeighties/0 in service 80s80s Mar 04 19:26:35 volumio volumio[1081]: info: [1772648795784] [80s80s] explodeUri: webeighties/0 Mar 04 19:26:35 volumio volumio[1081]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::saveQueue Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::updateTrackBlock Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::getTrackBlock Mar 04 19:26:35 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::play index 0 Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::stop Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::play index undefined Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:35 volumio volumio[1081]: info: CoreStateMachine::startPlaybackTimer Mar 04 19:26:35 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:35 volumio volumio[1081]: info: [1772648795799] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Mar 04 19:26:35 volumio volumio[1081]: info: [1772648795800] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Mar 04 19:26:35 volumio volumio[1081]: info: [1772648795980] [80s80s] received new event containing 2 songs. Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:26:35 volumio volumio[1081]: info: sendMpdCommand stop took 1 milliseconds Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:35 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:35 volumio volumio[1081]: info: sendMpdCommand clear took 3 milliseconds Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:35 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:35 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:35 volumio volumio[1081]: error: updateQueue error: null Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces state update: options Mar 04 19:26:35 volumio volumio[1081]: error: updateQueue error: null Mar 04 19:26:35 volumio volumio[1081]: error: updateQueue error: null Mar 04 19:26:35 volumio volumio[1081]: info: ------------------------------ 8ms Mar 04 19:26:35 volumio volumio[1081]: info: sendMpdCommand consume 1 took 6 milliseconds Mar 04 19:26:35 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:35 volumio volumio[1081]: info: ------------------------------ 6ms Mar 04 19:26:35 volumio volumio[1081]: info: ------------------------------ 5ms Mar 04 19:26:35 volumio volumio[1081]: info: [1772648795995] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces state update: options Mar 04 19:26:35 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:35 volumio volumio[1081]: info: Mar 04 19:26:35 volumio volumio[1081]: ---------------------------- MPD announces state update: options Mar 04 19:26:35 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:35 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:36 volumio volumio[1081]: error: Upnp client error: Error: This socket has been ended by the other party Mar 04 19:26:36 volumio volumio[1081]: info: Mar 04 19:26:36 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:36 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:36 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Mar 04 19:26:36 volumio volumio[1081]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 6 milliseconds Mar 04 19:26:36 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Mar 04 19:26:36 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:26:36 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:36 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand play Mar 04 19:26:36 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:36 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:36 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreStateMachine::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:36 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreStateMachine::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:36 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:36 volumio volumio[1081]: info: CoreStateMachine::pushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::volumioPushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 37ms Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 41ms Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 41ms Mar 04 19:26:36 volumio volumio[1081]: info: Mar 04 19:26:36 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:36 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:36 volumio volumio[1081]: info: Mar 04 19:26:36 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:36 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 42ms Mar 04 19:26:36 volumio volumio[1081]: info: sendMpdCommand play took 35 milliseconds Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 4ms Mar 04 19:26:36 volumio volumio[1081]: info: ------------------------------ 3ms Mar 04 19:26:36 volumio volumio[1081]: info: [1772648796045] [80s80s] Pushing the next song state: T'Pau - Heart and Soul Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music6/v4/70/d2/b5/70d2b5ed-726c-9aa6-6587-8c0a02fb6cd2/source/600x600bb.jpg","name":"T'Pau - Heart and Soul","title":"Heart and Soul","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"221","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 04 19:26:36 volumio volumio[1081]: verbose: CURRENT POSITION 0 Mar 04 19:26:36 volumio volumio[1081]: info: CoreStateMachine::syncState stateService play Mar 04 19:26:36 volumio volumio[1081]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:26:36 volumio volumio[1081]: info: [1772648796048] [80s80s] PlayNextTrack API delay: 30 Mar 04 19:26:36 volumio volumio[1081]: info: [1772648796060] [80s80s] Setting timer to: 221000 milliseconds. Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:36 volumio volumio[1081]: info: CorePlayQueue::getTrack 1 Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 3 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 20ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 18ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 17ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 19ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 15ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 15ms Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 8ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 6 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 7ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 4ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 3 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:38 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:38 volumio volumio[1081]: info: Mar 04 19:26:38 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 11ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 11 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 8ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 7ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand status took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 25ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 23ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 21ms Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:38 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:38 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:38 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 24ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 19ms Mar 04 19:26:38 volumio volumio[1081]: info: ------------------------------ 17ms Mar 04 19:26:44 volumio go-librespot[1575]: time="2026-03-04T19:26:44+01:00" level=trace msg="sent dealer ping" Mar 04 19:26:44 volumio go-librespot[1575]: time="2026-03-04T19:26:44+01:00" level=trace msg="received dealer pong" Mar 04 19:26:46 volumio volumio[1081]: info: CoreCommandRouter::volumioPlay Mar 04 19:26:46 volumio volumio[1081]: info: CoreStateMachine::play index undefined Mar 04 19:26:46 volumio volumio[1081]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:26:46 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:55 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:55 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces system playlist update Mar 04 19:26:55 volumio volumio[1081]: info: Ignoring MPD Status Update Mar 04 19:26:55 volumio volumio[1081]: info: Mar 04 19:26:55 volumio volumio[1081]: ---------------------------- MPD announces state update: player Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::getState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 7ms Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 6ms Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 4ms Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand status took 7 milliseconds Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseState Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 19:26:55 volumio volumio[1081]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:55 volumio volumio[1081]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:55 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:55 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:55 volumio volumio[1081]: info: ControllerMpd::pushState Mar 04 19:26:55 volumio volumio[1081]: info: CoreCommandRouter::servicePushState Mar 04 19:26:55 volumio volumio[1081]: info: CorePlayQueue::getTrack 0 Mar 04 19:26:55 volumio volumio[1081]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 21ms Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 19ms Mar 04 19:26:55 volumio volumio[1081]: info: ------------------------------ 17ms Mar 04 19:27:13 volumio go-librespot[1575]: time="2026-03-04T19:27:13+01:00" level=trace msg="received accesspoint ping" Mar 04 19:27:13 volumio go-librespot[1575]: time="2026-03-04T19:27:13+01:00" level=trace msg="received accesspoint pong ack" Mar 04 19:27:13 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Mar 04 19:27:13 volumio volumio[1081]: info: Cannot mount NAS Muziek-PC at system boot, trial number 2 ,retrying in 5 seconds Mar 04 19:27:14 volumio go-librespot[1575]: time="2026-03-04T19:27:14+01:00" level=trace msg="sent dealer ping" Mar 04 19:27:14 volumio go-librespot[1575]: time="2026-03-04T19:27:14+01:00" level=trace msg="received dealer pong" Mar 04 19:27:18 volumio volumio[1081]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:27:18 volumio volumio[1081]: TypeError: Cannot read property 'replace' of undefined Mar 04 19:27:18 volumio volumio[1081]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Mar 04 19:27:18 volumio volumio[1081]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Mar 04 19:27:18 volumio volumio[1081]: at listOnTimeout (internal/timers.js:557:17) Mar 04 19:27:18 volumio volumio[1081]: at processTimers (internal/timers.js:500:7) Mar 04 19:27:18 volumio volumio[1081]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:27:19 volumio sudo[2979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 19:26 Mar 04 19:27:19 volumio sudo[2979]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"