Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.387+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=130.659779ms timeout=3s volume=49 Aug 29 20:37:21 volumio volumio[26802]: info: VolumeController::SetAlsaVolume49 Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.391+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=-58.560706ms timeout=3s volume=48 Aug 29 20:37:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:37:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:37:21 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:37:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.408+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=260633 volume=49 Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.409+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:37:21 volumio volumio[26802]: info: VolumeController::SetAlsaVolume48 Aug 29 20:37:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:37:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:37:21 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:37:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.432+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=260633 volume=48 Aug 29 20:37:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:21.433+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 49 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 48 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 49 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 48 Aug 29 20:37:21 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.338+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=686.059184ms timeout=3s volume=47 Aug 29 20:37:22 volumio volumio[26802]: info: VolumeController::SetAlsaVolume47 Aug 29 20:37:22 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:37:22 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:37:22 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:37:22 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.354+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=499.915836ms timeout=3s volume=46 Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.359+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=261635 volume=47 Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.360+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:37:22 volumio volumio[26802]: info: VolumeController::SetAlsaVolume46 Aug 29 20:37:22 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:37:22 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:37:22 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:37:22 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.378+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=261635 volume=46 Aug 29 20:37:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:22.379+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 49 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 20:37:22 volumio volumio[26802]: info: Setting Spotify Volume from Volumio: 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 46 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 47 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 46 Aug 29 20:37:22 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:37:23 volumio volumio[26802]: SPOTIFY: SETTING SPOTIFY VOLUME 47 Aug 29 20:37:23 volumio volumio[26802]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 20:37:58 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:58.165+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s Aug 29 20:37:58 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:58.399+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=http://pushupdates.volumio.org duration=229.797944ms Aug 29 20:37:58 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:58.723+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=557.748861ms Aug 29 20:37:58 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:58.743+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://www.googleapis.com duration=574.965976ms Aug 29 20:37:59 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:59.411+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.242947866s Aug 29 20:37:59 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:59.414+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.24573124s Aug 29 20:37:59 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:59.417+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.248994508s Aug 29 20:37:59 volumio volumio5-onboarding[31278]: time=2026-08-29T20:37:59.417+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.248179146s Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.513+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=2.344936322s Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.518+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=1.522243579s timeout=3s volume=45 Aug 29 20:38:00 volumio volumio[26802]: info: VolumeController::SetAlsaVolume45 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.524+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=2.357156896s Aug 29 20:38:00 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:00 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.536+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=299697 volume=45 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.537+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 47 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 45 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 20:38:00 volumio volumio[26802]: info: Setting Spotify Volume from Volumio: 45 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.545+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://database.volumio.cloud duration=2.376434472s Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.551+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=1.342251775s timeout=3s volume=44 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.551+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=http://plugins.volumio.org duration=2.383041371s Aug 29 20:38:00 volumio volumio[26802]: info: VolumeController::SetAlsaVolume44 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.555+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=513.349674ms timeout=3s volume=43 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.561+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=https://google.com duration=2.39478554s Aug 29 20:38:00 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:00 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.570+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=299697 volume=44 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.571+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:00 volumio volumio[26802]: info: VolumeController::SetAlsaVolume43 Aug 29 20:38:00 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:00 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:00 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.594+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=299697 volume=43 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.595+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 45 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 44 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 45 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 43 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 20:38:00 volumio volumio[26802]: info: Setting Spotify Volume from Volumio: 43 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 44 Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:00 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Aug 29 20:38:00 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:00.653+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.194:50198 @ 0x1eec420" latency=348.490155ms timeout=10s endpoint=http://cddb.volumio.org duration=2.486687499s Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.431+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=273.198192ms timeout=3s volume=41 Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.431+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=473.160172ms timeout=3s volume=42 Aug 29 20:38:01 volumio volumio[26802]: info: VolumeController::SetAlsaVolume41 Aug 29 20:38:01 volumio volumio[26802]: info: VolumeController::SetAlsaVolume42 Aug 29 20:38:01 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:01 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:01 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:01 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:01 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:01 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:01 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.472+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=300712 volume=42 Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.473+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=300712 volume=42 Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.473+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:01 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:01.475+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 42 Aug 29 20:38:01 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:02 volumio volumio[26802]: SPOTIFY: SETTING SPOTIFY VOLUME 43 Aug 29 20:38:02 volumio volumio[26802]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 20:38:07 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 29 20:38:07 volumio volumio[26802]: info: CURURI: music-library/NAS/Aiaiaia/AiAiAi Aug 29 20:38:07 volumio volumio[26802]: info: Preload queue cleared Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.312+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=719.949395ms timeout=3s volume=40 Aug 29 20:38:09 volumio volumio[26802]: info: VolumeController::SetAlsaVolume40 Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.317+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=522.007707ms timeout=3s volume=39 Aug 29 20:38:09 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:09 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:09 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:09 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:09 volumio volumio[26802]: info: VolumeController::SetAlsaVolume39 Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.333+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=308471 volume=40 Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.334+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:09 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:09 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:09 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:09 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.348+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=308471 volume=39 Aug 29 20:38:09 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:09.349+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 43 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 20:38:09 volumio volumio[26802]: info: Setting Spotify Volume from Volumio: 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 39 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 40 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 39 Aug 29 20:38:09 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: false Aug 29 20:38:10 volumio volumio[26802]: SPOTIFY: SETTING SPOTIFY VOLUME 40 Aug 29 20:38:10 volumio volumio[26802]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 20:38:11 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:11.427+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.0.194:50198 @ 0x1eec420" latency=562.033555ms timeout=3s volume=38 Aug 29 20:38:11 volumio volumio[26802]: info: VolumeController::SetAlsaVolume38 Aug 29 20:38:11 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:11 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:11 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:11 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:11 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:11.443+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=310722 volume=38 Aug 29 20:38:11 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:11.444+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:11 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:11 volumio volumio[26802]: SPOTIFY: SPOTIFY VOLUME 40 Aug 29 20:38:11 volumio volumio[26802]: SPOTIFY: VOLUMIO VOLUME 38 Aug 29 20:38:11 volumio volumio[26802]: SPOTIFY: DELTA VOLUME ENOUGH: true Aug 29 20:38:11 volumio volumio[26802]: info: Setting Spotify Volume from Volumio: 38 Aug 29 20:38:11 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:12 volumio volumio[26802]: SPOTIFY: SETTING SPOTIFY VOLUME 38 Aug 29 20:38:12 volumio volumio[26802]: info: Sending Spotify command with payload to local API: /player/volume Aug 29 20:38:13 volumio volumio[26802]: info: [jellyfin-poller] Polled http://192.168.193.141: offline Aug 29 20:38:17 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:17 volumio volumio[26802]: info: CorePlayQueue::getTrack 47 Aug 29 20:38:17 volumio volumio[26802]: info: Prefetching next song Aug 29 20:38:17 volumio volumio[26802]: info: DOING PREFETCH IN MPD Aug 29 20:38:17 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand add "NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3" Aug 29 20:38:17 volumio volumio[26802]: info: Aug 29 20:38:17 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:17 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:17 volumio volumio[26802]: info: sendMpdCommand add "NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3" took 1 milliseconds Aug 29 20:38:17 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 29 20:38:17 volumio volumio[26802]: info: Aug 29 20:38:17 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:17 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:17 volumio volumio[26802]: info: Aug 29 20:38:17 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:17 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:17 volumio volumio[26802]: info: ------------------------------ 4ms Aug 29 20:38:17 volumio volumio[26802]: info: sendMpdCommand consume 1 took 3 milliseconds Aug 29 20:38:17 volumio volumio[26802]: info: ------------------------------ 2ms Aug 29 20:38:17 volumio volumio[26802]: info: ------------------------------ 2ms Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:21 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:21 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:21 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:21 volumio volumio[26802]: info: Aug 29 20:38:21 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 4ms Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand status took 4 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 4ms Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand status took 5 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand status took 4 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 5ms Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand status took 3 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::servicePushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"sex, and fruity","artist":"visuelle","album":null,"uri":"NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3","trackType":"mp3"} Aug 29 20:38:21 volumio volumio[26802]: verbose: CURRENT POSITION 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState stateService play Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState currentStatus play Aug 29 20:38:21 volumio volumio[26802]: info: Received an update from plugin. extracting info from payload Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.196+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.197+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.198+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.198+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 19ms Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 29 20:38:21 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:21 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::servicePushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"sex, and fruity","artist":"visuelle","album":null,"uri":"NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3","trackType":"mp3"} Aug 29 20:38:21 volumio volumio[26802]: verbose: CURRENT POSITION 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState stateService play Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState currentStatus play Aug 29 20:38:21 volumio volumio[26802]: info: Received an update from plugin. extracting info from payload Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio[26802]: info: ControllerMpd::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::servicePushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":322,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"224 Kbps","isStreaming":false,"title":"sex, and fruity","artist":"visuelle","album":null,"uri":"NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3","trackType":"mp3"} Aug 29 20:38:21 volumio volumio[26802]: verbose: CURRENT POSITION 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState stateService play Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::syncState currentStatus play Aug 29 20:38:21 volumio volumio[26802]: info: Received an update from plugin. extracting info from payload Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:21 volumio volumio[26802]: info: CorePlayQueue::getTrack 46 Aug 29 20:38:21 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.211+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=320488 volume=38 Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.212+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.212+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.212+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:21.212+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav" title="sex, and fruity (Edit).wav" Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 35ms Aug 29 20:38:21 volumio volumio[26802]: info: ------------------------------ 34ms Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:21 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:22 volumio volumio[26802]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:38:22 volumio volumio[26802]: info: CorePlayQueue::getTrack 47 Aug 29 20:38:22 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:22 volumio volumio[26802]: info: CorePlayQueue::getTrack 47 Aug 29 20:38:22 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:22 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:22.704+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_PLAYING positionMs=250 volume=38 Aug 29 20:38:22 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:22.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3" title="sex, and fruity" Aug 29 20:38:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:22 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:29 volumio volumio[26802]: info: Preload queue cleared Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::ClearQueue Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::stPlaybackTimer Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::updateTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 47 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::serviceStop Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 47 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::serviceStop Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::stop Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::clearPlayQueue Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::saveQueue Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::addQueueItems Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::addQueueItems Aug 29 20:38:29 volumio volumio[26802]: info: Preload queue cleared Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/#Gevluchte dromen....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/#Gevluchte dromen....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/3 seconden winst... MAN (Edit).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/3 seconden winst... MAN (Edit).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/;Gewoon~Wijffie!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/;Gewoon~Wijffie!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/;Old Times~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/;Old Times~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' (Remastered).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' (Remastered).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edit.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edit.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edit1.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edit1.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edittttttt.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edittttttt.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edittttttt2222.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Edittttttt2222.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Master 2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Master 2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Master.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul' D-Master.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul'-new txt.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul'-new txt.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul'.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Art reflects 'soul'.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Basshook Metonym.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Basshook Metonym.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Basshook Metonym2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Basshook Metonym2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Bo'r'd Verkeer.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Bo'r'd Verkeer.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/CataBana Pussy.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/CataBana Pussy.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Dartstreets.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Dartstreets.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Drip Drop....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Drip Drop....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Driven Round.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Driven Round.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Duidelijk toch!!!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Duidelijk toch!!!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/FatBike Face... achterop.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/FatBike Face... achterop.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Gevluchte dromen....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Gevluchte dromen....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Geweest... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Geweest... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Geweest....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Geweest....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Guitar Stitches1.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Guitar Stitches1.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Guitar Stitches2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Guitar Stitches2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/klankschaal, mooie stem, flow,.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/klankschaal, mooie stem, flow,.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Lick or ~Swallow~yes, swing.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Lick or ~Swallow~yes, swing.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Lieve Mams.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Lieve Mams.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Lorre...snorren...mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Lorre...snorren...mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Maskers... van Beton... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Maskers... van Beton... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/MmmmmMonsters Inside.....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/MmmmmMonsters Inside.....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/MmmmmMonsters.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/MmmmmMonsters.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Oorlog als een bonbon~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Oorlog als een bonbon~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/ORangE.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/ORangE.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Pigeons will fly you Home, Daddy.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Pigeons will fly you Home, Daddy.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Poison Frogs.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Poison Frogs.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Posttraumatic storm '10-8-9'.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Posttraumatic storm '10-8-9'.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/PralinePeace....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/PralinePeace....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/PurpleSparks.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/PurpleSparks.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Rainbow Riders.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Rainbow Riders.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Sad bread 02.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Sad bread 02.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Sad bread remaster70-80 (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Sad bread remaster70-80 (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Sad bread remaster70-80.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Sad bread remaster70-80.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Sad bread.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Sad bread.mp3 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.629+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.629+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3" title="sex, and fruity" Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::saveQueue Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::updateTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPlay Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::play index 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::addQueueItems Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::addQueueItems Aug 29 20:38:29 volumio volumio[26802]: info: Preload queue cleared Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/sex, and fruity (Edit).wav Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/sex, and fruity.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/ShimmerShine....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/ShimmerShine....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Spinning thoughts everywhere.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Spinning thoughts everywhere.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/SriRacha!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/SriRacha!.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/tarada da wartaal.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/tarada da wartaal.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/TwinkelsWhy....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/TwinkelsWhy....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Tza-tziki!!!~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Tza-tziki!!!~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Uhoorn Darts 2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Uhoorn Darts 2.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Uhoorn Darts.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Uhoorn Darts.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Unfold (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Unfold (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Unfold.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Unfold.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Wartaal... tada da da.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Wartaal... tada da da.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Whistling Colors (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Whistling Colors (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Whistling Colors.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Whistling Colors.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Witness date... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Witness date... (1).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/Witness date....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/Witness date....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/^SriRacha^.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/^SriRacha^.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/_Co co die latte~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/_Co co die latte~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/_PurpleSparks~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/_PurpleSparks~.mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/_Whistling Colors~.mp3 (Remix).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/_Whistling Colors~.mp3 (Remix).mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Adding Item to queue: music-library/NAS/Aiaiaia/AiAiAi/°°Wauwie....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: Using cached record of: music-library/NAS/Aiaiaia/AiAiAi/°°Wauwie....mp3 Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushQueue Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::saveQueue Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::play index undefined Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::updateTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrackBlock Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::startPlaybackTimer Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::clearAddPlayTracks NAS/Aiaiaia/AiAiAi/Sad bread.mp3 Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand stop Aug 29 20:38:29 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:29 volumio volumio[26802]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand stop took 60 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand stop took 16 milliseconds Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand clear Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces state update: player Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::getState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand status Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:29 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand status took 7 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand status took 7 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand clear took 6 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand status took 6 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand status took 5 milliseconds Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand add "NAS/Aiaiaia/AiAiAi/Sad bread.mp3" Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseState Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::servicePushState Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 29 20:38:29 volumio volumio[26802]: verbose: CURRENT POSITION 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::syncState stateService stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::syncState currentStatus stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: No code Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: ControllerMpd::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::servicePushState Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 29 20:38:29 volumio volumio[26802]: verbose: CURRENT POSITION 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::syncState stateService stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::syncState currentStatus stop Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio[26802]: info: No code Aug 29 20:38:29 volumio volumio[26802]: info: CoreStateMachine::pushState Aug 29 20:38:29 volumio volumio[26802]: info: CorePlayQueue::getTrack 45 Aug 29 20:38:29 volumio volumio[26802]: info: CoreCommandRouter::volumioPushState Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.699+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.703+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.703+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.700+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" state=STATUS_STOPPED positionMs=0 volume=38 Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio5-onboarding[31278]: time=2026-08-29T20:38:29.704+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.194:50198 @ 0x1eec420" id="mnt/NAS/Aiaiaia/AiAiAi/Sad bread.mp3" title="Sad bread" Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 27ms Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 27ms Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:29 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:29 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:29 volumio volumio[26802]: error: updateQueue error: null Aug 29 20:38:29 volumio volumio[26802]: info: Aug 29 20:38:29 volumio volumio[26802]: ---------------------------- MPD announces system playlist update Aug 29 20:38:29 volumio volumio[26802]: info: Ignoring MPD Status Update Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 34ms Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand playlistinfo took 33 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: sendMpdCommand add "NAS/Aiaiaia/AiAiAi/Sad bread.mp3" took 33 milliseconds Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 8ms Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 7ms Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::parseTrackInfo Aug 29 20:38:29 volumio volumio[26802]: verbose: ControllerMpd::sendMpdCommand play Aug 29 20:38:29 volumio volumio[26802]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 20:38:29 volumio volumio[26802]: info: ------------------------------ 45ms Aug 29 20:38:29 volumio volumio[26802]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:38:29 volumio volumio[26802]: TypeError: Cannot read properties of undefined (reading 'split') Aug 29 20:38:29 volumio volumio[26802]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Aug 29 20:38:29 volumio volumio[26802]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 29 20:38:29 volumio volumio[26802]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 29 20:38:29 volumio volumio[26802]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 20:38:30 volumio sudo[7168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-29 20:37' Aug 29 20:38:30 volumio sudo[7168]: 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"