Apr 10 15:11:01 volumio volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 10 15:11:07 volumio volumio[1216]: info: [jellyfin-poller] Polled Https://10.0.0.100:8096: offline Apr 10 15:11:09 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:09.661-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" latency=-429.045701ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 10 15:11:10 volumio sudo[8012]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 15:11:10 volumio sudo[8012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:11:10 volumio sudo[8014]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 15:11:10 volumio sudo[8014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:11:10 volumio sudo[8012]: pam_unix(sudo:session): session closed for user root Apr 10 15:11:10 volumio sudo[8014]: pam_unix(sudo:session): session closed for user root Apr 10 15:11:10 volumio volumio[1216]: verbose: New Socket.io Connection to 10.0.0.102 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Apr 10 15:11:10 volumio sudo[8018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 15:11:10 volumio sudo[8018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:11:10 volumio sudo[8018]: pam_unix(sudo:session): session closed for user root Apr 10 15:11:10 volumio sudo[8020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 15:11:10 volumio sudo[8020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:11:10 volumio sudo[8020]: pam_unix(sudo:session): session closed for user root Apr 10 15:11:10 volumio volumio[1216]: verbose: New Socket.io Connection to 10.0.0.102 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 10 15:11:10 volumio volumio[1216]: info: Received Get System Info Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:11:10 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:10 volumio volumio[1216]: info: Listing playlists Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 15:11:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 15:11:12 volumio volumio[1216]: info: Received Get System Info Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:11:12 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Apr 10 15:11:12 volumio volumio[1216]: info: CoreStateMachine::getQueue Apr 10 15:11:12 volumio volumio[1216]: info: CorePlayQueue::getQueue Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 15:11:12 volumio volumio[1216]: info: Received Get System Info Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:11:12 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:12 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:11:20 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPlay Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::play index 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::stop Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::stPlaybackTimer Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::updateTrackBlock Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrackBlock Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 56 Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::serviceStop Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 56 Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::serviceStop Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::stop Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.468-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_STOPPED positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.468-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_STOPPED positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.468-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=85e8d4cb2380b56041f97658f09cf4e7" title="Woman in Black (Early Version 1)" Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.468-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=85e8d4cb2380b56041f97658f09cf4e7" title="Woman in Black (Early Version 1)" Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand stop took 16 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::play index undefined Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85 Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 1 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.501-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.501-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.502-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.502-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 17ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.520-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.520-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.520-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.520-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.521-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.522-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_STOPPED positionMs=3 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.522-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.522-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 44ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 41ms Apr 10 15:11:24 volumio volumio[1216]: info: Pushing Favourites {"favourite":false} Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play] Stream URL for Animals: http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand stop Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand stop took 1 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand clear Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand clear took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac" Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: error: updateQueue error: null Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 6ms Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play]: Reported 'stop' for song: Woman in Black (Early Version 1) (at 42849 ms) Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand addid "http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac" Apr 10 15:11:24 volumio volumio[1216]: error: updateQueue error: null Apr 10 15:11:24 volumio volumio[1216]: error: updateQueue error: null Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 34ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 34ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand addid "http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac" took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: MPD COMMAND [object Object] Apr 10 15:11:24 volumio volumio[1216]: verbose: MPD COMMAND [object Object] Apr 10 15:11:24 volumio volumio[1216]: verbose: MPD COMMAND [object Object] Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand play Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 6ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 5ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand play took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 3ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 2ms Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 3ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 2ms Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play]: Mark song Animals as played by timeshadowrider. Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 25 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 28 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 28 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 27 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus stop Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 34ms Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 7 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 7 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.685-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.685-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.685-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.685-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.686-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.686-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.686-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.688-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.688-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.688-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.688-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.689-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.689-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.687-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.689-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.690-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 57ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 57ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 32ms Apr 10 15:11:24 volumio volumio[1216]: info: Pushing Favourites {"favourite":false} Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 32 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 31 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.721-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.722-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.722-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.722-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.722-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.722-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=0 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.723-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.723-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 60ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 56ms Apr 10 15:11:24 volumio volumio[1216]: info: Pushing Favourites {"favourite":false} Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Apr 10 15:11:24 volumio volumio[1216]: info: Ignoring MPD Status Update Apr 10 15:11:24 volumio volumio[1216]: info: Apr 10 15:11:24 volumio volumio[1216]: ---------------------------- MPD announces state update: player Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::getState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 39 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 40ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 37 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 4ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 3ms Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseState Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play]: Reported 'start' for song: Animals (at 0 ms) Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: ControllerMpd::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Apr 10 15:11:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 25 Apr 10 15:11:24 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Animals","artist":"Muse","album":"The 2nd Law","uri":"http://10.0.0.100:8096/Audio/b65974f485eab00ae97b84e5b60f7f85/stream.flac?static=true&mediaSourceId=b65974f485eab00ae97b84e5b60f7f85&tag=e5676ebfbaf46e8a8923a4424910948b&t.flac","trackType":"flac"} Apr 10 15:11:24 volumio volumio[1216]: verbose: CURRENT POSITION 25 Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Apr 10 15:11:24 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreStateMachine::pushState Apr 10 15:11:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.767-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.768-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.768-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.769-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.769-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.769-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.769-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.770-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.771-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.771-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.771-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.771-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.771-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.770-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 70ms Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.772-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.775-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.775-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.775-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.775-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.775-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 36ms Apr 10 15:11:24 volumio volumio[1216]: info: ------------------------------ 36ms Apr 10 15:11:24 volumio volumio[1216]: info: Pushing Favourites {"favourite":false} Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.777-07:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.783-07:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" state=STATUS_PLAYING positionMs=164 volume=25 Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play]: Reported 'timeupdate' for song: Animals (at 0 ms) Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.787-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.161:60906 @ 0x1fda1b0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio5-onboarding[1599]: time=2026-04-10T15:11:24.789-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" id="jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/song@songId=b65974f485eab00ae97b84e5b60f7f85" title=Animals Apr 10 15:11:24 volumio volumio[1216]: info: [jellyfin-play]: Reported 'timeupdate' for song: Animals (at 871 ms) Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 15:11:29 volumio volumio[1216]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 10 15:11:29 volumio volumio[1216]: info: Received Get System Version Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 15:11:29 volumio volumio[1216]: info: Received Get System Info Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:11:29 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:11:29 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:11:37 volumio volumio[1216]: info: [jellyfin-poller] Polled Https://10.0.0.100:8096: offline Apr 10 15:12:00 volumio volumio5-onboarding[1599]: time=2026-04-10T15:12:00.008-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%01,10.0.0.161:60906 @ 0x1d4e8d0" latency=-429.846764ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Apr 10 15:12:00 volumio sudo[8208]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 15:12:00 volumio sudo[8208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:12:00 volumio sudo[8208]: pam_unix(sudo:session): session closed for user root Apr 10 15:12:00 volumio sudo[8210]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 15:12:00 volumio sudo[8210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:12:00 volumio sudo[8210]: pam_unix(sudo:session): session closed for user root Apr 10 15:12:00 volumio volumio[1216]: verbose: New Socket.io Connection to 10.0.0.102 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 10 15:12:00 volumio sudo[8214]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 10 15:12:00 volumio sudo[8214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:12:00 volumio sudo[8214]: pam_unix(sudo:session): session closed for user root Apr 10 15:12:00 volumio sudo[8216]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 10 15:12:00 volumio sudo[8216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 10 15:12:00 volumio sudo[8216]: pam_unix(sudo:session): session closed for user root Apr 10 15:12:00 volumio volumio[1216]: verbose: New Socket.io Connection to 10.0.0.102 from 10.0.0.161 UA: Mozilla/5.0 (Linux; Android 16; Pixel 6a Build/CP1A.260305.018; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.178 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 10 15:12:00 volumio volumio[1216]: info: Received Get System Info Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:12:00 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:00 volumio volumio[1216]: info: Listing playlists Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 15:12:00 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Apr 10 15:12:02 volumio volumio[1216]: info: CoreStateMachine::getQueue Apr 10 15:12:02 volumio volumio[1216]: info: CorePlayQueue::getQueue Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 15:12:02 volumio volumio[1216]: info: Received Get System Info Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:12:02 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 10 15:12:02 volumio volumio[1216]: info: Received Get System Info Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:12:02 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:02 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:12:07 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 10 15:12:07 volumio volumio[1216]: info: [jellyfin-browse] browseUri: jellyfin Apr 10 15:12:07 volumio volumio[1216]: info: [jellyfin-poller] Polled Https://10.0.0.100:8096: offline Apr 10 15:12:07 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:08 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 10 15:12:08 volumio volumio[1216]: info: [jellyfin-browse] browseUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4 Apr 10 15:12:08 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 10 15:12:08 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/UserViews?userId=5de21c3f10f044c3b55ed175d6955314 Apr 10 15:12:08 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Apr 10 15:12:08 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:08 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:08 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 10 15:12:10 volumio volumio[1216]: info: [jellyfin-browse] browseUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["DatePlayed","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["PlayCount","SortName"],"sortOrder":["Descending","Ascending"],"fields":["MediaSources"],"filters":["IsPlayed"],"includeItemTypes":["Audio"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["SortName"],"sortOrder":["Ascending"],"filters":["IsFavorite"],"includeItemTypes":["MusicAlbum"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":20,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"filters":["IsFavorite"],"includeItemTypes":["Audio"]} Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&includeItemTypes=MusicAlbum&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:10 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=DatePlayed&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&fields=MediaSources&includeItemTypes=Audio&filters=IsFavorite&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:10 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Artists?startIndex=0&limit=20&parentId=7e64e319657a9516ec78490da03edccb&filters=IsFavorite&imageTypeLimit=1&enableImageTypes=Primary&userId=5de21c3f10f044c3b55ed175d6955314&sortBy=SortName&sortOrder=Ascending Apr 10 15:12:11 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Items?userId=5de21c3f10f044c3b55ed175d6955314&startIndex=0&limit=20&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=7e64e319657a9516ec78490da03edccb&fields=MediaSources&includeItemTypes=Audio&filters=IsPlayed&sortBy=PlayCount&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 10 15:12:11 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:11 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:11 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=b65974f485eab00ae97b84e5b60f7f85 Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=85e8d4cb2380b56041f97658f09cf4e7 Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=d2c8f0e4a4d11feaf1433ef931120d1a Apr 10 15:12:11 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=d2c8f0e4a4d11feaf1433ef931120d1a Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=b65974f485eab00ae97b84e5b60f7f85 Apr 10 15:12:11 volumio volumio[1216]: info: Preloading song: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=85e8d4cb2380b56041f97658f09cf4e7 Apr 10 15:12:11 volumio volumio[1216]: info: Exploding uri jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=d2c8f0e4a4d11feaf1433ef931120d1a in service jellyfin Apr 10 15:12:11 volumio volumio[1216]: info: [jellyfin-browse] explodeUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=d2c8f0e4a4d11feaf1433ef931120d1a Apr 10 15:12:11 volumio volumio[1216]: info: Exploding uri jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=b65974f485eab00ae97b84e5b60f7f85 in service jellyfin Apr 10 15:12:11 volumio volumio[1216]: info: [jellyfin-browse] explodeUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=b65974f485eab00ae97b84e5b60f7f85 Apr 10 15:12:11 volumio volumio[1216]: info: Exploding uri jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=85e8d4cb2380b56041f97658f09cf4e7 in service jellyfin Apr 10 15:12:11 volumio volumio[1216]: info: [jellyfin-browse] explodeUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/song@songId=85e8d4cb2380b56041f97658f09cf4e7 Apr 10 15:12:13 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 10 15:12:13 volumio volumio[1216]: info: [jellyfin-browse] browseUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/artists@parentId=7e64e319657a9516ec78490da03edccb Apr 10 15:12:13 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 10 15:12:14 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Genres?startIndex=0&limit=47&parentId=7e64e319657a9516ec78490da03edccb&imageTypeLimit=1&enableImageTypes=Primary&userId=5de21c3f10f044c3b55ed175d6955314&sortBy=SortName&sortOrder=Ascending Apr 10 15:12:14 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 10 15:12:14 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Artists?startIndex=0&limit=47&parentId=7e64e319657a9516ec78490da03edccb&imageTypeLimit=1&enableImageTypes=Primary&userId=5de21c3f10f044c3b55ed175d6955314&sortBy=SortName&sortOrder=Ascending Apr 10 15:12:14 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:14 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 10 15:12:18 volumio volumio[1216]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 10 15:12:18 volumio volumio[1216]: info: Received Get System Version Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 10 15:12:18 volumio volumio[1216]: info: Received Get System Info Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 10 15:12:18 volumio volumio[1216]: info: Discovery: Getting this device information Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Apr 10 15:12:18 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 10 15:12:19 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 10 15:12:20 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 10 15:12:20 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 10 15:12:28 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 10 15:12:28 volumio volumio[1216]: info: [jellyfin-browse] browseUri: jellyfin/timeshadowrider@dcc6a90c16fa4f0d8d652d0ce8ab1ca4/library@parentId=7e64e319657a9516ec78490da03edccb/artists@parentId=7e64e319657a9516ec78490da03edccb@startIndex=47@noExplode=1 Apr 10 15:12:28 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 10 15:12:28 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Genres?startIndex=0&limit=47&parentId=7e64e319657a9516ec78490da03edccb&imageTypeLimit=1&enableImageTypes=Primary&userId=5de21c3f10f044c3b55ed175d6955314&sortBy=SortName&sortOrder=Ascending Apr 10 15:12:28 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"5de21c3f10f044c3b55ed175d6955314","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7e64e319657a9516ec78490da03edccb","startIndex":47,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 10 15:12:28 volumio volumio[1216]: verbose: [jellyfin] getItemsFromAPI(): http://10.0.0.100:8096/Artists?startIndex=47&limit=47&parentId=7e64e319657a9516ec78490da03edccb&imageTypeLimit=1&enableImageTypes=Primary&userId=5de21c3f10f044c3b55ed175d6955314&sortBy=SortName&sortOrder=Ascending Apr 10 15:12:28 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:28 volumio volumio[1216]: info: Preload queue cleared Apr 10 15:12:31 volumio volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 15:12:31 volumio volumio[1216]: TypeError: Cannot read properties of null (reading 'slice') Apr 10 15:12:31 volumio volumio[1216]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 10 15:12:31 volumio volumio[1216]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 10 15:12:31 volumio volumio[1216]: at Object.onceWrapper (node:events:629:26) Apr 10 15:12:31 volumio volumio[1216]: at ClientRequest.emit (node:events:514:28) Apr 10 15:12:31 volumio volumio[1216]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Apr 10 15:12:31 volumio volumio[1216]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Apr 10 15:12:31 volumio volumio[1216]: at TLSSocket.socketOnData (node:_http_client:535:22) Apr 10 15:12:31 volumio volumio[1216]: at TLSSocket.emit (node:events:514:28) Apr 10 15:12:31 volumio volumio[1216]: at addChunk (node:internal/streams/readable:343:12) Apr 10 15:12:31 volumio volumio[1216]: at readableAddChunk (node:internal/streams/readable:316:9) Apr 10 15:12:31 volumio volumio[1216]: at Readable.push (node:internal/streams/readable:253:10) Apr 10 15:12:31 volumio volumio[1216]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Apr 10 15:12:31 volumio volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 15:12:31 volumio sudo[8364]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-10 15:11' Apr 10 15:12:31 volumio sudo[8364]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"