-- Logs begin at Sun 2026-03-01 20:57:48 CET, end at Wed 2026-03-04 11:13:57 CET. -- Mar 04 11:12:18 volumio go-librespot[1293]: time="2026-03-04T11:12:18+01:00" level=trace msg="sent dealer ping" Mar 04 11:12:18 volumio go-librespot[1293]: time="2026-03-04T11:12:18+01:00" level=trace msg="received dealer pong" Mar 04 11:12:31 volumio volumio[962]: info: Mar 04 11:12:31 volumio volumio[962]: ---------------------------- MPD announces system playlist update Mar 04 11:12:31 volumio volumio[962]: info: Ignoring MPD Status Update Mar 04 11:12:31 volumio volumio[962]: info: Mar 04 11:12:31 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:12:31 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:12:31 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:12:31 volumio volumio[962]: info: ------------------------------ 1ms Mar 04 11:12:31 volumio volumio[962]: info: sendMpdCommand status took 1 milliseconds Mar 04 11:12:31 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:12:31 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:12:31 volumio volumio[962]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 04 11:12:31 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:12:31 volumio volumio[962]: info: ControllerMpd::pushState Mar 04 11:12:31 volumio volumio[962]: info: CoreCommandRouter::servicePushState Mar 04 11:12:31 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:12:31 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":559126,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ralph Hildenbeutel - Hommage A Noir","artist":"1.FM - Chillout Lounge Radio","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Mar 04 11:12:31 volumio volumio[962]: verbose: CURRENT POSITION 0 Mar 04 11:12:31 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Mar 04 11:12:31 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:12:31 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Mar 04 11:12:31 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:12:31 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:12:31 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:12:31 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:12:31 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:12:31 volumio volumio5-onboarding[1262]: time=2026-03-04T11:12:31.190+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=558032 volume=100 Mar 04 11:12:31 volumio volumio5-onboarding[1262]: time=2026-03-04T11:12:31.190+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=http://185.33.21.112:80/chilloutlounge_128 title="Ralph Hildenbeutel - Hommage A Noir" Mar 04 11:12:31 volumio volumio5-onboarding[1262]: time=2026-03-04T11:12:31.190+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=558032 volume=100 Mar 04 11:12:31 volumio volumio5-onboarding[1262]: time=2026-03-04T11:12:31.191+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=http://185.33.21.112:80/chilloutlounge_128 title="Ralph Hildenbeutel - Hommage A Noir" Mar 04 11:12:31 volumio volumio[962]: info: ------------------------------ 8ms Mar 04 11:12:31 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:12:31 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:12:48 volumio go-librespot[1293]: time="2026-03-04T11:12:48+01:00" level=trace msg="sent dealer ping" Mar 04 11:12:48 volumio go-librespot[1293]: time="2026-03-04T11:12:48+01:00" level=trace msg="received dealer pong" Mar 04 11:13:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:13:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:13:03 volumio volumio[962]: info: Discovery: Getting this device information Mar 04 11:13:03 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:13:04 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.178.20:3000 from 192.168.178.45 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 04 11:13:04 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 04 11:13:04 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 04 11:13:17 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:17.995+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.45:55608 @ 0x25462d0" latency=973.7µs timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 04 11:13:18 volumio go-librespot[1293]: time="2026-03-04T11:13:18+01:00" level=trace msg="sent dealer ping" Mar 04 11:13:18 volumio go-librespot[1293]: time="2026-03-04T11:13:18+01:00" level=trace msg="received dealer pong" Mar 04 11:13:18 volumio sudo[27888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 11:13:18 volumio sudo[27888]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 11:13:18 volumio sudo[27888]: pam_unix(sudo:session): session closed for user root Mar 04 11:13:18 volumio sudo[27891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 11:13:18 volumio sudo[27891]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 11:13:18 volumio sudo[27891]: pam_unix(sudo:session): session closed for user root Mar 04 11:13:18 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.178.20 from 192.168.178.45 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 04 11:13:18 volumio sudo[27894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 04 11:13:18 volumio sudo[27894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 11:13:18 volumio sudo[27894]: pam_unix(sudo:session): session closed for user root Mar 04 11:13:18 volumio sudo[27897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 04 11:13:18 volumio sudo[27897]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 04 11:13:18 volumio sudo[27897]: pam_unix(sudo:session): session closed for user root Mar 04 11:13:18 volumio volumio[962]: verbose: New Socket.io Connection to 192.168.178.20 from 192.168.178.45 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.120 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetVisibleSources Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 04 11:13:18 volumio volumio[962]: info: Received Get System Info Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:13:18 volumio volumio[962]: info: Discovery: Getting this device information Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:18 volumio volumio[962]: info: Listing playlists Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 11:13:18 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::volumioGetQueue Mar 04 11:13:20 volumio volumio[962]: info: CoreStateMachine::getQueue Mar 04 11:13:20 volumio volumio[962]: info: CorePlayQueue::getQueue Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 11:13:20 volumio volumio[962]: info: Received Get System Info Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:13:20 volumio volumio[962]: info: Discovery: Getting this device information Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:13:21 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 11:13:21 volumio volumio[962]: info: Received Get System Info Mar 04 11:13:21 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:13:21 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:13:21 volumio volumio[962]: info: Discovery: Getting this device information Mar 04 11:13:21 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:21 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:13:25 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 11:13:25 volumio volumio[962]: info: Preload queue cleared Mar 04 11:13:28 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 04 11:13:36 volumio volumio[962]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 04 11:13:36 volumio volumio[962]: info: Received Get System Version Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 11:13:36 volumio volumio[962]: info: Received Get System Info Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:13:36 volumio volumio[962]: info: Discovery: Getting this device information Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Mar 04 11:13:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:13:38 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 11:13:38 volumio volumio[962]: info: TuneIn handleBrowseUri: tunein/popular Mar 04 11:13:38 volumio volumio[962]: info: Preload queue cleared Mar 04 11:13:48 volumio go-librespot[1293]: time="2026-03-04T11:13:48+01:00" level=trace msg="sent dealer ping" Mar 04 11:13:48 volumio go-librespot[1293]: time="2026-03-04T11:13:48+01:00" level=trace msg="received dealer pong" Mar 04 11:13:52 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 04 11:13:53 volumio volumio[962]: info: Preload queue cleared Mar 04 11:13:54 volumio volumio[962]: info: Preload queue cleared Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::ClearQueue Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::stop Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::stPlaybackTimer Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrackBlock Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::serviceStop Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::serviceStop Mar 04 11:13:54 volumio volumio[962]: info: [1772619234030] ControllerWebradio::stop Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::clearPlayQueue Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::saveQueue Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::addQueueItems Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::addQueueItems Mar 04 11:13:54 volumio volumio[962]: info: Preload queue cleared Mar 04 11:13:54 volumio volumio[962]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 04 11:13:54 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:54.032+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 04 11:13:54 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:54.032+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" title="1.FM - Chillout Lounge (www.1.fm)" Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::saveQueue Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrackBlock Mar 04 11:13:54 volumio volumio[962]: info: CoreCommandRouter::volumioPlay Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::play index 0 Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::stop Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::play index undefined Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:13:54 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:54 volumio volumio[962]: info: [1772619234036] ControllerWebradio::clearAddPlayTrack Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:13:54 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:54 volumio volumio[962]: info: Mar 04 11:13:54 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand stop took 18 milliseconds Mar 04 11:13:54 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand stop took 13 milliseconds Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand status took 1 milliseconds Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:13:54 volumio volumio[962]: info: Mar 04 11:13:54 volumio volumio[962]: ---------------------------- MPD announces system playlist update Mar 04 11:13:54 volumio volumio[962]: info: Ignoring MPD Status Update Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand clear took 1 milliseconds Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:13:54 volumio volumio[962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 04 11:13:54 volumio volumio[962]: info: ------------------------------ 3ms Mar 04 11:13:54 volumio volumio[962]: error: updateQueue error: null Mar 04 11:13:54 volumio volumio[962]: info: ------------------------------ 7ms Mar 04 11:13:54 volumio volumio[962]: info: Mar 04 11:13:54 volumio volumio[962]: ---------------------------- MPD announces system playlist update Mar 04 11:13:54 volumio volumio[962]: info: Ignoring MPD Status Update Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 825 milliseconds Mar 04 11:13:54 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:13:54 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand play Mar 04 11:13:54 volumio volumio[962]: info: ------------------------------ 1ms Mar 04 11:13:54 volumio volumio[962]: info: sendMpdCommand play took 1 milliseconds Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand status took 2 milliseconds Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::servicePushState Mar 04 11:13:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:56 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5971/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 11:13:56 volumio volumio[962]: verbose: CURRENT POSITION 0 Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 9ms Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::servicePushState Mar 04 11:13:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:56 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5971/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 11:13:56 volumio volumio[962]: verbose: CURRENT POSITION 0 Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:13:56 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.771+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 10ms Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.771+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.772+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces system playlist update Mar 04 11:13:56 volumio volumio[962]: info: Ignoring MPD Status Update Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 1ms Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand status took 1 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces system playlist update Mar 04 11:13:56 volumio volumio[962]: info: Ignoring MPD Status Update Mar 04 11:13:56 volumio volumio[962]: info: Mar 04 11:13:56 volumio volumio[962]: ---------------------------- MPD announces state update: player Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::getState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::servicePushState Mar 04 11:13:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:56 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 11:13:56 volumio volumio[962]: verbose: CURRENT POSITION 0 Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:13:56 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.783+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.783+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.784+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.784+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 9ms Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 8ms Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand status took 8 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseState Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:56 volumio volumio[962]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 11:13:56 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:13:56 volumio volumio[962]: info: ControllerMpd::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::servicePushState Mar 04 11:13:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Mar 04 11:13:56 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Tracklist: https://dancewave.online","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 04 11:13:56 volumio volumio[962]: verbose: CURRENT POSITION 0 Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:13:56 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio[962]: info: CoreStateMachine::pushState Mar 04 11:13:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.794+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=186 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.795+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.795+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" state=STATUS_PLAYING positionMs=186 volume=100 Mar 04 11:13:56 volumio volumio5-onboarding[1262]: time=2026-03-04T11:13:56.795+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.45:55608 @ 0x25462d0" id=https://dancewave.online:443/dance.mp3 title="Tracklist: https://dancewave.online" Mar 04 11:13:56 volumio volumio[962]: info: ------------------------------ 18ms Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 04 11:13:57 volumio volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:13:57 volumio volumio[962]: TypeError: Cannot read property 'slice' of null Mar 04 11:13:57 volumio volumio[962]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 04 11:13:57 volumio volumio[962]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 04 11:13:57 volumio volumio[962]: at Object.onceWrapper (events.js:422:26) Mar 04 11:13:57 volumio volumio[962]: at ClientRequest.emit (events.js:315:20) Mar 04 11:13:57 volumio volumio[962]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Mar 04 11:13:57 volumio volumio[962]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Mar 04 11:13:57 volumio volumio[962]: at HTTPParser.execute () Mar 04 11:13:57 volumio volumio[962]: at TLSSocket.socketOnData (_http_client.js:509:22) Mar 04 11:13:57 volumio volumio[962]: at TLSSocket.emit (events.js:315:20) Mar 04 11:13:57 volumio volumio[962]: at addChunk (internal/streams/readable.js:309:12) Mar 04 11:13:57 volumio volumio[962]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 04 11:13:57 volumio volumio[962]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Mar 04 11:13:57 volumio volumio[962]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 04 11:13:57 volumio volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:13:57 volumio go-librespot[1293]: time="2026-03-04T11:13:57+01:00" level=trace msg="received accesspoint ping" Mar 04 11:13:57 volumio go-librespot[1293]: time="2026-03-04T11:13:57+01:00" level=trace msg="received accesspoint pong ack" Mar 04 11:13:57 volumio sudo[27969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-04 11:12 Mar 04 11:13:57 volumio sudo[27969]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"