Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.490+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.22:53027 Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.510+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.22:53027 @ 0x24b6660" latency=-28.128265ms timeout=20s Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.510+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.510+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.22:53027 @ 0x24b6660" latency=-27.595047ms platform=PLATFORM_IOS version=6.260807.0 Aug 28 14:09:13 volumio volumio[1249]: info: Received Get System Info Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:13 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.513+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" name=Volumio Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.515+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" language=de Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.516+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" timezone=Europe/Berlin Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.517+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" available=true connected=false macAddress= ip4Address= ip6Address= Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.521+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" available=true connected=true macAddress=e4:5f:01:1b:18:f9 ip4Address=192.168.178.28/24 ip6Address= ssid="FRITZ!Box 7530 DM" Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.521+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" setupComplete=true Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 14:09:13 volumio volumio[1249]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 28 14:09:13 volumio volumio[1249]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 28 14:09:13 volumio volumio[1249]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Aug 28 14:09:13 volumio volumio[1249]: amixer -c 2 info | grep "vc4-hdmi-0" Aug 28 14:09:13 volumio volumio[1249]: Card sysdefault:2 'vc4hdmi0'/'vc4-hdmi-0' Aug 28 14:09:13 volumio volumio[1249]: amixer -c 3 info | grep "vc4-hdmi-1" Aug 28 14:09:13 volumio volumio[1249]: Card sysdefault:3 'vc4hdmi1'/'vc4-hdmi-1' Aug 28 14:09:13 volumio volumio[1249]: amixer -c 4 info | grep "IQaudIODAC" Aug 28 14:09:13 volumio volumio[1249]: Card sysdefault:4 'IQaudIODAC'/'IQaudIODAC' Aug 28 14:09:13 volumio volumio[1249]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 4 Aug 28 14:09:13 volumio volumio[1249]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 28 14:09:13 volumio volumio[1249]: {"cmd":"/usr/local/bin/alsacap -C 4","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 4\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 28 14:09:13 volumio volumio[1249]: amixer -c 4 info | grep "IQaudIO DAC Plus" Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.674+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" selectedOutputId=4 Aug 28 14:09:13 volumio volumio[1249]: info: Received Get System Info Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:13 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.690+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" currentVersion=4.103 latestVersion=4.119 Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.691+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.22:53027 @ 0x24b6660" status=UPDATE_STATUS_NONE progress=0 Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.691+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" userId= Aug 28 14:09:13 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:13.691+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" providers=3 Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:09:13 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 28 14:09:14 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:14.327+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" plugins=71 Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:14 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:14.330+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=101206 volume=100 Aug 28 14:09:14 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:14.330+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=cbe9b8e389532e2306eedc30595ce38e" title="Le Tue Parole" Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:14 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:14 volumio volumio[1249]: verbose: New Socket.io Connection to 192.168.178.28:3000 from 192.168.178.22 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 14:09:14 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.088+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.22:53027 @ 0x24b6660" latency=-27.2499ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.113+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.146+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=http://pushupdates.volumio.org duration=30.536597ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.281+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=167.695082ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.400+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://www.googleapis.com duration=284.383508ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.424+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://securetoken.googleapis.com duration=310.845745ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.454+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=341.126567ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.454+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=339.073136ms Aug 28 14:09:15 volumio sudo[12441]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 14:09:15 volumio sudo[12439]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 14:09:15 volumio sudo[12439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:09:15 volumio sudo[12441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:09:15 volumio sudo[12439]: pam_unix(sudo:session): session closed for user root Aug 28 14:09:15 volumio sudo[12441]: pam_unix(sudo:session): session closed for user root Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.504+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=391.480707ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.526+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=http://cddb.volumio.org duration=410.429424ms Aug 28 14:09:15 volumio volumio[1249]: verbose: New Socket.io Connection to 192.168.178.28 from 192.168.178.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.555+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://functions.volumio.cloud duration=439.077849ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.556+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://functions.volumio.cloud duration=441.414684ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.558+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://google.com duration=442.498547ms Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.664+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=https://database.volumio.cloud duration=546.875062ms Aug 28 14:09:15 volumio sudo[12445]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 14:09:15 volumio sudo[12445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:09:15 volumio sudo[12445]: pam_unix(sudo:session): session closed for user root Aug 28 14:09:15 volumio sudo[12447]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 14:09:15 volumio sudo[12447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 14:09:15 volumio sudo[12447]: pam_unix(sudo:session): session closed for user root Aug 28 14:09:15 volumio volumio[1249]: verbose: New Socket.io Connection to 192.168.178.28 from 192.168.178.22 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Aug 28 14:09:15 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:15.721+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.22:53027 @ 0x24b6660" latency=-30.095905ms timeout=10s endpoint=http://plugins.volumio.org duration=604.061006ms Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 14:09:15 volumio volumio[1249]: info: Listing playlists Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 14:09:15 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 14:09:17 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:09:17 volumio volumio[1249]: info: Received Get System Info Aug 28 14:09:17 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:17 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:17 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:17 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:17 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 14:09:18 volumio volumio[1249]: info: Received Get System Info Aug 28 14:09:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:18 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:18 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:18 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:21 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 28 14:09:21 volumio volumio[1249]: info: [jellyfin-browse] browseUri: jellyfin Aug 28 14:09:21 volumio volumio[1249]: info: CoreCommandRouter::volumioGetQueue Aug 28 14:09:21 volumio volumio[1249]: info: CoreStateMachine::getQueue Aug 28 14:09:21 volumio volumio[1249]: info: CorePlayQueue::getQueue Aug 28 14:09:21 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:09:23 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 28 14:09:23 volumio volumio[1249]: info: [jellyfin-browse] browseUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9 Aug 28 14:09:23 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"6ae504d5eec5466b91368ceef326d15b","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Aug 28 14:09:23 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.178.20:8096/UserViews?userId=6ae504d5eec5466b91368ceef326d15b Aug 28 14:09:23 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"6ae504d5eec5466b91368ceef326d15b","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"8a05b0252259a1dbd62df97522638439","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Aug 28 14:09:24 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.178.20:8096/Items?userId=6ae504d5eec5466b91368ceef326d15b&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=8a05b0252259a1dbd62df97522638439&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 28 14:09:24 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:09:24 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:09:33 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 14:09:33 volumio volumio[1249]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 28 14:09:33 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 28 14:09:34 volumio volumio[1249]: info: Received Get System Version Aug 28 14:09:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 14:09:34 volumio volumio[1249]: info: Received Get System Info Aug 28 14:09:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 14:09:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 14:09:34 volumio volumio[1249]: info: Discovery: Getting this device information Aug 28 14:09:34 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Aug 28 14:09:34 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::volumioNext Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::next Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 14:09:40 volumio volumio[1249]: info: ControllerMpd::next Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand next Aug 28 14:09:40 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:40 volumio volumio[1249]: info: Aug 28 14:09:40 volumio volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 14:09:40 volumio volumio[1249]: info: sendMpdCommand next took 6 milliseconds Aug 28 14:09:40 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:40 volumio volumio[1249]: info: sendMpdCommand status took 2 milliseconds Aug 28 14:09:40 volumio volumio[1249]: info: sendMpdCommand status took 3 milliseconds Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:40 volumio volumio[1249]: info: ControllerMpd::pushState Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 4 Aug 28 14:09:40 volumio volumio[1249]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 28 14:09:40 volumio volumio[1249]: verbose: CURRENT POSITION 4 Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::syncState stateService stop Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::play index undefined Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.828+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=127740 volume=100 Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.828+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_STOPPED positionMs= volume=100 Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.828+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=cbe9b8e389532e2306eedc30595ce38e" title="Le Tue Parole" Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.829+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::startPlaybackTimer Aug 28 14:09:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:40 volumio volumio[1249]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647 Aug 28 14:09:40 volumio volumio[1249]: info: ------------------------------ 13ms Aug 28 14:09:40 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:40 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:09:40 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.835+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 14:09:40 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:40.836+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:40 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:09:40 volumio volumio[1249]: info: FusionDsp - Volumio is not playing Aug 28 14:09:40 volumio volumio[1249]: info: FusionDsp - Clipped samples monitor stopped Aug 28 14:09:40 volumio volumio[1249]: info: FusionDsp - Volumio is not playing Aug 28 14:09:40 volumio volumio[1249]: info: FusionDsp - Clipped samples monitor stopped Aug 28 14:09:40 volumio volumio[1249]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=cbe9b8e389532e2306eedc30595ce38e","favourite":false} Aug 28 14:09:40 volumio volumio[1249]: info: [jellyfin-play]: Reported 'stop' for song: Le Tue Parole (at 128631 ms) Aug 28 14:09:40 volumio volumio[1249]: info: [jellyfin-play] Stream URL for Sempre Sempre: http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 14:09:40 volumio volumio[1249]: info: sendMpdCommand stop took 1 milliseconds Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 14:09:40 volumio volumio[1249]: info: Aug 28 14:09:40 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 14:09:40 volumio volumio[1249]: info: Ignoring MPD Status Update Aug 28 14:09:40 volumio volumio[1249]: info: sendMpdCommand clear took 1 milliseconds Aug 28 14:09:40 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac" Aug 28 14:09:40 volumio volumio[1249]: error: updateQueue error: null Aug 28 14:09:40 volumio volumio[1249]: info: ------------------------------ 2ms Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac" Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand addid "http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac" took 0 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: MPD COMMAND [object Object] Aug 28 14:09:41 volumio volumio[1249]: verbose: MPD COMMAND [object Object] Aug 28 14:09:41 volumio volumio[1249]: verbose: MPD COMMAND [object Object] Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 14:09:41 volumio volumio[1249]: info: Ignoring MPD Status Update Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 14:09:41 volumio volumio[1249]: info: Ignoring MPD Status Update Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand play Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 2ms Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 1ms Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand play took 2 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: [jellyfin-play]: Mark song Sempre Sempre as played by lorebern. Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 5 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 3 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 3 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 2 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 14:09:41 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:41 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sempre Sempre","artist":"Andrea Bocelli","album":"Andrea Bocelli","uri":"http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac","trackType":"flac"} Aug 28 14:09:41 volumio volumio[1249]: verbose: CURRENT POSITION 5 Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus stop Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 10ms Aug 28 14:09:41 volumio volumio[1249]: info: Pushing Favourites {"favourite":false} Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 14:09:41 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:41 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sempre Sempre","artist":"Andrea Bocelli","album":"Andrea Bocelli","uri":"http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac","trackType":"flac"} Aug 28 14:09:41 volumio volumio[1249]: verbose: CURRENT POSITION 5 Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Aug 28 14:09:41 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.304+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=0 volume=100 Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.304+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=0 volume=100 Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.304+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.304+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 25ms Aug 28 14:09:41 volumio volumio[1249]: info: Pushing Favourites {"favourite":false} Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:09:41 volumio volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces system playlist update Aug 28 14:09:41 volumio volumio[1249]: info: Ignoring MPD Status Update Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: info: Aug 28 14:09:41 volumio volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 3 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 4ms Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand status took 4 milliseconds Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: info: Pushing Favourites {"favourite":false} Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:09:41 volumio volumio[1249]: info: [jellyfin-play]: Reported 'start' for song: Sempre Sempre (at 0 ms) Aug 28 14:09:41 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 28 14:09:41 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:09:41 volumio volumio[1249]: info: ControllerMpd::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 14:09:41 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:09:41 volumio volumio[1249]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"445 Kbps","isStreaming":false,"title":"Sempre Sempre","artist":"Andrea Bocelli","album":"Andrea Bocelli","uri":"http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac","trackType":"flac"} Aug 28 14:09:41 volumio volumio[1249]: verbose: CURRENT POSITION 5 Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState stateService play Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus play Aug 28 14:09:41 volumio volumio[1249]: info: Received an update from plugin. extracting info from payload Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:09:41 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.351+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=162 volume=100 Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.351+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=162 volume=100 Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.351+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:41 volumio volumio5-onboarding[1635]: time=2026-08-28T14:09:41.351+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:09:41 volumio volumio[1249]: info: ------------------------------ 27ms Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:09:41 volumio volumio[1249]: info: [jellyfin-play]: Reported 'timeupdate' for song: Sempre Sempre (at 0 ms) Aug 28 14:09:41 volumio volumio[1249]: info: [jellyfin-play]: Reported 'timeupdate' for song: Sempre Sempre (at 962 ms) Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Aug 28 14:09:41 volumio volumio[1249]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 28 14:09:41 volumio volumio[1249]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 28 14:09:41 volumio volumio[1249]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 28 14:09:43 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:09:43 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:10:06 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Aug 28 14:10:06 volumio volumio[1249]: info: [jellyfin-browse] browseUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47 Aug 28 14:10:06 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"6ae504d5eec5466b91368ceef326d15b","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"fe466fa9bfa5c501c3b7edaf33bd5d47","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Aug 28 14:10:07 volumio volumio[1249]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.178.20:8096/Items?userId=6ae504d5eec5466b91368ceef326d15b&startIndex=0&recursive=true&sortOrder=Ascending&parentId=fe466fa9bfa5c501c3b7edaf33bd5d47&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Aug 28 14:10:07 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=3a6f505961f0acf75501b3eff9478882 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=937d3486b5d4a8a421662555d22e1b10 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1c9e59cb0b99079fdf97e2bf7d1ae4d5 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=e3b1bcd8c3873ac9308e81756672edac Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=54568f9619895778097278ae115d644b Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=18187a9b694378895659423c1c2a6c31 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=2bcba00d9e6a51aa023a2a3b8436b893 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=04ad5855efc325a369d4c3e3028f632f Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=36103669638e7aee6bcd38b026d81379 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=ea83a6f7fc3fc2ce3716dea7c74069c6 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=bd5b9f5878bb8a9343c772f94321de94 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=f8dc8a028c073e8e4489d435d7673725 Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=724c5bfaea279d0c45c2c905e5d36bfe Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=9d630b0e7cc42b1193a8bbdf76d2e27c Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1f13a10b4a3efe4bf420aaf165241cfa Aug 28 14:10:07 volumio volumio[1249]: info: Preloading song: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=d5b185828cb672f133379d7f41c2432e Aug 28 14:10:07 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:10:11 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::ClearQueue Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::stop Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::stPlaybackTimer Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::serviceStop Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::serviceStop Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 14:10:11 volumio volumio[1249]: info: ControllerMpd::stop Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::clearPlayQueue Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::saveQueue Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushQueue Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::addQueueItems Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::addQueueItems Aug 28 14:10:11 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=3a6f505961f0acf75501b3eff9478882 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=3a6f505961f0acf75501b3eff9478882 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=3a6f505961f0acf75501b3eff9478882 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=937d3486b5d4a8a421662555d22e1b10 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=937d3486b5d4a8a421662555d22e1b10 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=937d3486b5d4a8a421662555d22e1b10 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1c9e59cb0b99079fdf97e2bf7d1ae4d5 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1c9e59cb0b99079fdf97e2bf7d1ae4d5 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1c9e59cb0b99079fdf97e2bf7d1ae4d5 Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.732+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_STOPPED positionMs=0 volume=100 Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.732+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Volumio is not playing Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Clipped samples monitor stopped Aug 28 14:10:11 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:10:11 volumio volumio[1249]: info: Aug 28 14:10:11 volumio volumio[1249]: ---------------------------- MPD announces state update: player Aug 28 14:10:11 volumio volumio[1249]: info: sendMpdCommand stop took 16 milliseconds Aug 28 14:10:11 volumio volumio[1249]: info: ControllerMpd::getState Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand status Aug 28 14:10:11 volumio volumio[1249]: info: sendMpdCommand status took 2 milliseconds Aug 28 14:10:11 volumio volumio[1249]: info: sendMpdCommand status took 1 milliseconds Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::parseState Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 14:10:11 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 14:10:11 volumio volumio[1249]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:10:11 volumio volumio[1249]: verbose: ControllerMpd::parseTrackInfo Aug 28 14:10:11 volumio volumio[1249]: info: ControllerMpd::pushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::servicePushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrack 5 Aug 28 14:10:11 volumio volumio[1249]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sempre Sempre","artist":"Andrea Bocelli","album":"Andrea Bocelli","uri":"http://192.168.178.20:8096/Audio/fe3404a1f183f3f5768035bc444a4647/stream.flac?static=true&mediaSourceId=fe3404a1f183f3f5768035bc444a4647&tag=6a73338673ed05f097b7cd1dcfb9913a&t.flac","trackType":"flac"} Aug 28 14:10:11 volumio volumio[1249]: verbose: CURRENT POSITION 5 Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::syncState stateService stop Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::syncState currentStatus stop Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:10:11 volumio volumio[1249]: info: No code Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::pushState Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.757+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=0 volume=100 Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.757+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.758+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=0 volume=100 Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.758+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.759+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" state=STATUS_PLAYING positionMs=0 volume=100 Aug 28 14:10:11 volumio volumio5-onboarding[1635]: time=2026-08-28T14:10:11.760+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.22:53027 @ 0x24b6660" id="jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647" title="Sempre Sempre" Aug 28 14:10:11 volumio volumio[1249]: info: ------------------------------ 19ms Aug 28 14:10:11 volumio volumio[1249]: info: Pushing Favourites {"favourite":false} Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:10:11 volumio volumio[1249]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Volumio is playing Aug 28 14:10:11 volumio volumio[1249]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=fe3404a1f183f3f5768035bc444a4647","favourite":false} Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:10:11 volumio volumio[1249]: info: FusionDsp - Clipping Monitor started Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-play]: Reported 'stop' for song: Sempre Sempre (at 31395 ms) Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushQueue Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::saveQueue Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPlay Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::play index 2 Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::addQueueItems Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::addQueueItems Aug 28 14:10:11 volumio volumio[1249]: info: Preload queue cleared Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=e3b1bcd8c3873ac9308e81756672edac Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=e3b1bcd8c3873ac9308e81756672edac in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=e3b1bcd8c3873ac9308e81756672edac Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=54568f9619895778097278ae115d644b Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=54568f9619895778097278ae115d644b in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=54568f9619895778097278ae115d644b Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=18187a9b694378895659423c1c2a6c31 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=18187a9b694378895659423c1c2a6c31 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=18187a9b694378895659423c1c2a6c31 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=2bcba00d9e6a51aa023a2a3b8436b893 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=2bcba00d9e6a51aa023a2a3b8436b893 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=2bcba00d9e6a51aa023a2a3b8436b893 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=04ad5855efc325a369d4c3e3028f632f Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=04ad5855efc325a369d4c3e3028f632f in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=04ad5855efc325a369d4c3e3028f632f Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=36103669638e7aee6bcd38b026d81379 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=36103669638e7aee6bcd38b026d81379 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=36103669638e7aee6bcd38b026d81379 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=ea83a6f7fc3fc2ce3716dea7c74069c6 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=ea83a6f7fc3fc2ce3716dea7c74069c6 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=ea83a6f7fc3fc2ce3716dea7c74069c6 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=bd5b9f5878bb8a9343c772f94321de94 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=bd5b9f5878bb8a9343c772f94321de94 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=bd5b9f5878bb8a9343c772f94321de94 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=f8dc8a028c073e8e4489d435d7673725 Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=f8dc8a028c073e8e4489d435d7673725 in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=f8dc8a028c073e8e4489d435d7673725 Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=724c5bfaea279d0c45c2c905e5d36bfe Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=724c5bfaea279d0c45c2c905e5d36bfe in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=724c5bfaea279d0c45c2c905e5d36bfe Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=9d630b0e7cc42b1193a8bbdf76d2e27c Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=9d630b0e7cc42b1193a8bbdf76d2e27c in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=9d630b0e7cc42b1193a8bbdf76d2e27c Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1f13a10b4a3efe4bf420aaf165241cfa Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1f13a10b4a3efe4bf420aaf165241cfa in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=1f13a10b4a3efe4bf420aaf165241cfa Aug 28 14:10:11 volumio volumio[1249]: info: Adding Item to queue: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=d5b185828cb672f133379d7f41c2432e Aug 28 14:10:11 volumio volumio[1249]: info: Exploding uri jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=d5b185828cb672f133379d7f41c2432e in service jellyfin Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-browse] explodeUri: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/songs@albumId=fe466fa9bfa5c501c3b7edaf33bd5d47/song@songId=d5b185828cb672f133379d7f41c2432e Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::stop Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::play index undefined Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrack 2 Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::startPlaybackTimer Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrack 2 Aug 28 14:10:11 volumio volumio[1249]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/lorebern@a0d6774c3d7f4d3caacc5a0d3cc186a9/song@songId=1c9e59cb0b99079fdf97e2bf7d1ae4d5 Aug 28 14:10:11 volumio volumio[1249]: info: CoreCommandRouter::volumioPushQueue Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::saveQueue Aug 28 14:10:11 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Aug 28 14:10:11 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Aug 28 14:10:11 volumio volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:10:11 volumio volumio[1249]: Error: No media streams found for song 03-Puerto Rico Aug 28 14:10:11 volumio volumio[1249]: at PlayController._PlayController_getStreamUrl (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:241:15) Aug 28 14:10:11 volumio volumio[1249]: at PlayController.clearAddPlayTrack (/data/plugins/music_service/jellyfin/dist/lib/controller/play/PlayController.js:78:234) Aug 28 14:10:11 volumio volumio[1249]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 28 14:10:11 volumio volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 14:10:12 volumio sudo[12571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 14:09' Aug 28 14:10:12 volumio sudo[12571]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"